builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-030 starttime: 1491431176.1 results: success (0) revision: b95fcbceb5b39ade0054b2fd114c19bc2ed91ef8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.104867) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.105392) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.105688) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.120908) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.121315) ========= 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=3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-05 15:26:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2017-04-05 15:26:16 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095054 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.230668) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.230989) ========= 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=3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013641 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.281089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.281468) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b95fcbceb5b39ade0054b2fd114c19bc2ed91ef8 --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 b95fcbceb5b39ade0054b2fd114c19bc2ed91ef8 --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=3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-05 15:26:16,396 truncating revision to first 12 chars 2017-04-05 15:26:16,396 Setting DEBUG logging. 2017-04-05 15:26:16,396 attempt 1/10 2017-04-05 15:26:16,396 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b95fcbceb5b3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-05 15:26:16,693 unpacking tar archive at: mozilla-aurora-b95fcbceb5b3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.676879 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.972128) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.972467) ========= script_repo_revision: b95fcbceb5b39ade0054b2fd114c19bc2ed91ef8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.972877) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.973154) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 15:26:16.983511) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-05 15:26:16.983962) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:26:17 INFO - MultiFileLogger online at 20170405 15:26:17 in /builds/slave/test 15:26:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:26:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:26:17 INFO - {'append_to_log': False, 15:26:17 INFO - 'base_work_dir': '/builds/slave/test', 15:26:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:26:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:26:17 INFO - 'branch': 'Mozilla-Aurora', 15:26:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:26:17 INFO - 'config_files': ('talos/linux_config.py',), 15:26:17 INFO - 'default_actions': ('clobber', 15:26:17 INFO - 'read-buildbot-config', 15:26:17 INFO - 'download-and-extract', 15:26:17 INFO - 'populate-webroot', 15:26:17 INFO - 'create-virtualenv', 15:26:17 INFO - 'install', 15:26:17 INFO - 'run-tests'), 15:26:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:26:17 INFO - 'download_minidump_stackwalk': True, 15:26:17 INFO - 'download_symbols': 'ondemand', 15:26:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:26:17 INFO - 'tooltool.py': '/tools/tooltool.py', 15:26:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:26:17 INFO - '/tools/misc-python/virtualenv.py')}, 15:26:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:26:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:26:17 INFO - 'gecko_profile': False, 15:26:17 INFO - 'gecko_profile_interval': 0, 15:26:17 INFO - 'installer_path': 'installer.exe', 15:26:17 INFO - 'log_level': 'info', 15:26:17 INFO - 'log_name': 'talos', 15:26:17 INFO - 'log_to_console': True, 15:26:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:26:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:26:17 INFO - 'opt_config_files': (), 15:26:17 INFO - 'pip_index': False, 15:26:17 INFO - 'suite': 'dromaeojs', 15:26:17 INFO - 'system_bits': '32', 15:26:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:26:17 INFO - 'title': 'talos-linux64-ix-030', 15:26:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:26:17 INFO - 'use_talos_json': True, 15:26:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:26:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:26:17 INFO - 'work_dir': 'build'} 15:26:17 INFO - [mozharness: 2017-04-05 22:26:17.188535Z] Running clobber step. 15:26:17 INFO - Running pre-action listener: _resource_record_pre_action 15:26:17 INFO - Running main action method: clobber 15:26:17 INFO - rmtree: /builds/slave/test/build 15:26:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:26:18 INFO - Running post-action listener: _resource_record_post_action 15:26:18 INFO - [mozharness: 2017-04-05 22:26:18.128340Z] Finished clobber step (success) 15:26:18 INFO - [mozharness: 2017-04-05 22:26:18.128441Z] Running read-buildbot-config step. 15:26:18 INFO - Running pre-action listener: _resource_record_pre_action 15:26:18 INFO - Running main action method: read_buildbot_config 15:26:18 INFO - Using buildbot properties: 15:26:18 INFO - { 15:26:18 INFO - "project": "", 15:26:18 INFO - "product": "firefox", 15:26:18 INFO - "who": "ryanvm@gmail.com", 15:26:18 INFO - "installer_path": "public/build/target.tar.bz2", 15:26:18 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:26:18 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 15:26:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:26:18 INFO - "stage_platform": "linux64-pgo", 15:26:18 INFO - "basedir": "/builds/slave/test", 15:26:18 INFO - "buildnumber": 1139, 15:26:18 INFO - "platform": "ubuntu64_hw", 15:26:18 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:26:18 INFO - "slavebuilddir": "test", 15:26:18 INFO - "taskId": "aeMNWpKbQvmp4jydtVW0Kg", 15:26:18 INFO - "branch": "mozilla-aurora", 15:26:18 INFO - "script_repo_revision": "production", 15:26:18 INFO - "revision": "b95fcbceb5b39ade0054b2fd114c19bc2ed91ef8", 15:26:18 INFO - "slavename": "talos-linux64-ix-030", 15:26:18 INFO - "repo_path": "releases/mozilla-aurora" 15:26:18 INFO - } 15:26:18 INFO - Finding installer, test and symbols from parent task. 15:26:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aeMNWpKbQvmp4jydtVW0Kg'}, attempt #1 15:26:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aeMNWpKbQvmp4jydtVW0Kg'}, attempt #1 15:26:19 INFO - Task dependencies: EUr_yszeQHelf3G5I6a8WQ 15:26:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ'}, attempt #1 15:26:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.tar.bz2 15:26:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/test_packages.json 15:26:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.crashreporter-symbols.zip 15:26:19 INFO - Running post-action listener: _resource_record_post_action 15:26:19 INFO - [mozharness: 2017-04-05 22:26:19.591938Z] Finished read-buildbot-config step (success) 15:26:19 INFO - [mozharness: 2017-04-05 22:26:19.592156Z] Running download-and-extract step. 15:26:19 INFO - Running pre-action listener: _resource_record_pre_action 15:26:19 INFO - Running main action method: download_and_extract 15:26:19 INFO - mkdir: /builds/slave/test/build/tests 15:26:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:19 INFO - trying https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.test_packages.json 15:26:19 INFO - Downloading https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:26:20 INFO - Downloaded 896 bytes. 15:26:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:26:20 INFO - Using the following test package requirements: 15:26:20 INFO - {u'common': [u'target.common.tests.zip'], 15:26:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:26:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:26:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:26:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:26:20 INFO - u'mozbase': [u'target.common.tests.zip'], 15:26:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:26:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:26:20 INFO - u'web-platform': [u'target.common.tests.zip', 15:26:20 INFO - u'target.web-platform.tests.zip'], 15:26:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:26:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:26:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.common.tests.zip 15:26:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:26:20 INFO - Fetch https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.common.tests.zip into memory 15:26:21 INFO - Expected file size: 59541357 15:26:21 INFO - Obtained file size: 59541357 15:26:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:26:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.common.tests.zip 15:26:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:26:30 INFO - Fetch https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.common.tests.zip into memory 15:26:32 INFO - Expected file size: 59541357 15:26:32 INFO - Obtained file size: 59541357 15:26:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.talos.tests.zip 15:26:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:26:42 INFO - Fetch https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.talos.tests.zip into memory 15:26:43 INFO - Expected file size: 14404438 15:26:43 INFO - Obtained file size: 14404438 15:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:43 INFO - trying https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.tar.bz2 15:26:43 INFO - Downloading https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:26:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:26:45 INFO - Downloaded 66780826 bytes. 15:26:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.tar.bz2 15:26:45 INFO - mkdir: /builds/slave/test/properties 15:26:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:26:45 INFO - Writing to file /builds/slave/test/properties/build_url 15:26:45 INFO - Contents: 15:26:45 INFO - build_url:https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.tar.bz2 15:26:45 INFO - Running post-action listener: _resource_record_post_action 15:26:45 INFO - Running post-action listener: set_extra_try_arguments 15:26:45 INFO - [mozharness: 2017-04-05 22:26:45.020570Z] Finished download-and-extract step (success) 15:26:45 INFO - [mozharness: 2017-04-05 22:26:45.020666Z] Running populate-webroot step. 15:26:45 INFO - Running pre-action listener: _resource_record_pre_action 15:26:45 INFO - Running main action method: populate_webroot 15:26:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:26:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:26:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:26:45 INFO - '--tppagecycles', 15:26:45 INFO - '7'], 15:26:45 INFO - 'tests': ['tsvgm']}}, 15:26:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:26:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:26:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:26:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:26:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:26:45 INFO - 'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:26:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:26:45 INFO - 'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['damp', 'tps']}, 15:26:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:26:45 INFO - 'tests': ['damp', 'tps']}, 15:26:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['dromaeo_dom']}, 15:26:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:26:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['basic_compositor_video']}, 15:26:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:26:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['a11yr', 15:26:45 INFO - 'ts_paint', 15:26:45 INFO - 'tpaint', 15:26:45 INFO - 'sessionrestore', 15:26:45 INFO - 'sessionrestore_no_auto_restore', 15:26:45 INFO - 'tabpaint']}, 15:26:45 INFO - 'other-e10s': {'tests': ['a11yr', 15:26:45 INFO - 'ts_paint', 15:26:45 INFO - 'tpaint', 15:26:45 INFO - 'sessionrestore', 15:26:45 INFO - 'sessionrestore_no_auto_restore', 15:26:45 INFO - 'tabpaint']}, 15:26:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:26:45 INFO - 'ts_paint', 15:26:45 INFO - 'tpaint', 15:26:45 INFO - 'sessionrestore', 15:26:45 INFO - 'sessionrestore_no_auto_restore', 15:26:45 INFO - 'tabpaint']}, 15:26:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:26:45 INFO - 'ts_paint', 15:26:45 INFO - 'tpaint', 15:26:45 INFO - 'sessionrestore', 15:26:45 INFO - 'sessionrestore_no_auto_restore', 15:26:45 INFO - 'tabpaint']}, 15:26:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['a11yr', 15:26:45 INFO - 'ts_paint', 15:26:45 INFO - 'tpaint', 15:26:45 INFO - 'sessionrestore', 15:26:45 INFO - 'sessionrestore_no_auto_restore', 15:26:45 INFO - 'tabpaint']}, 15:26:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['a11yr', 15:26:45 INFO - 'ts_paint', 15:26:45 INFO - 'tpaint', 15:26:45 INFO - 'sessionrestore', 15:26:45 INFO - 'sessionrestore_no_auto_restore', 15:26:45 INFO - 'tabpaint']}, 15:26:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['tsvgx', 15:26:45 INFO - 'tsvgr_opacity', 15:26:45 INFO - 'tart', 15:26:45 INFO - 'tscrollx', 15:26:45 INFO - 'cart', 15:26:45 INFO - 'tsvg_static']}, 15:26:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:26:45 INFO - 'tsvgr_opacity', 15:26:45 INFO - 'tart', 15:26:45 INFO - 'tscrollx', 15:26:45 INFO - 'cart', 15:26:45 INFO - 'tsvg_static']}, 15:26:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:26:45 INFO - 'talos_options': ['--disable-e10s'], 15:26:45 INFO - 'tests': ['tp5o']}, 15:26:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:26:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:26:45 INFO - 'talos_options': ['--disable-e10s', 15:26:45 INFO - '--xperf_path', 15:26:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:45 INFO - 'tests': ['tp5n']}, 15:26:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:26:45 INFO - 'talos_options': ['--xperf_path', 15:26:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:45 INFO - 'tests': ['tp5n']}}, 15:26:45 INFO - 'talos.zip': {'path': '', 15:26:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:26:45 INFO - Running post-action listener: _resource_record_post_action 15:26:45 INFO - [mozharness: 2017-04-05 22:26:45.033798Z] Finished populate-webroot step (success) 15:26:45 INFO - [mozharness: 2017-04-05 22:26:45.033886Z] Running create-virtualenv step. 15:26:45 INFO - Running pre-action listener: _resource_record_pre_action 15:26:45 INFO - Running main action method: create_virtualenv 15:26:45 INFO - Creating virtualenv /builds/slave/test/build/venv 15:26:45 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 15:26:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:26:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:26:45 INFO - Using real prefix '/usr' 15:26:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:26:45 INFO - Installing distribute.............................................................................................................................................................................................done. 15:26:47 INFO - Installing pip.................done. 15:26:47 INFO - Return code: 0 15:26:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:26:47 INFO - Reading from file tmpfile_stdout 15:26:47 INFO - Output received: 15:26:47 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) 15:26:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:26: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')]} 15:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26: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')]} 15:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:47 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:26:47 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 15:26:47 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 15:26:47 INFO - Using env: {'DISPLAY': ':0', 15:26:47 INFO - 'HOME': '/home/cltbld', 15:26:47 INFO - 'LANG': 'en_US.UTF-8', 15:26:47 INFO - 'LANGUAGE': 'en_US:en', 15:26:47 INFO - 'LOGNAME': 'cltbld', 15:26:47 INFO - 'MAIL': '/var/mail/cltbld', 15:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:47 INFO - 'MOZ_NO_REMOTE': '1', 15:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:47 INFO - 'NO_EM_RESTART': '1', 15:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:47 INFO - 'PWD': '/builds/slave/test', 15:26:47 INFO - 'SHELL': '/bin/bash', 15:26:47 INFO - 'SHLVL': '1', 15:26:47 INFO - 'TERM': 'linux', 15:26:47 INFO - 'TMOUT': '86400', 15:26:47 INFO - 'USER': 'cltbld', 15:26:47 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:47 INFO - '_': '/tools/buildbot/bin/python'} 15:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:48 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 15:26:48 INFO - Cleaning up... 15:26:48 INFO - Return code: 0 15:26:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:48 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:26:48 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 15:26:48 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 15:26:48 INFO - Using env: {'DISPLAY': ':0', 15:26:48 INFO - 'HOME': '/home/cltbld', 15:26:48 INFO - 'LANG': 'en_US.UTF-8', 15:26:48 INFO - 'LANGUAGE': 'en_US:en', 15:26:48 INFO - 'LOGNAME': 'cltbld', 15:26:48 INFO - 'MAIL': '/var/mail/cltbld', 15:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:48 INFO - 'MOZ_NO_REMOTE': '1', 15:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:48 INFO - 'NO_EM_RESTART': '1', 15:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:48 INFO - 'PWD': '/builds/slave/test', 15:26:48 INFO - 'SHELL': '/bin/bash', 15:26:48 INFO - 'SHLVL': '1', 15:26:48 INFO - 'TERM': 'linux', 15:26:48 INFO - 'TMOUT': '86400', 15:26:48 INFO - 'USER': 'cltbld', 15:26:48 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:48 INFO - '_': '/tools/buildbot/bin/python'} 15:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:48 INFO - Downloading/unpacking psutil>=3.1.1 15:26:48 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 15:26:48 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 15:26:48 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 15:26:48 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 15:26:48 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 15:26:48 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 15:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:26:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:50 INFO - Installing collected packages: psutil 15:26:50 INFO - Running setup.py install for psutil 15:26:50 INFO - building 'psutil._psutil_linux' extension 15:26:50 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 15:26:50 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 15:26:50 INFO - building 'psutil._psutil_posix' extension 15:26:50 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 15:26:50 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 15:26:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:50 INFO - Successfully installed psutil 15:26:50 INFO - Cleaning up... 15:26:50 INFO - Return code: 0 15:26:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:26:50 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')]} 15:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:50 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')]} 15:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:50 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:26:50 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 15:26:50 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 15:26:50 INFO - Using env: {'DISPLAY': ':0', 15:26:50 INFO - 'HOME': '/home/cltbld', 15:26:50 INFO - 'LANG': 'en_US.UTF-8', 15:26:50 INFO - 'LANGUAGE': 'en_US:en', 15:26:50 INFO - 'LOGNAME': 'cltbld', 15:26:50 INFO - 'MAIL': '/var/mail/cltbld', 15:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:50 INFO - 'MOZ_NO_REMOTE': '1', 15:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:50 INFO - 'NO_EM_RESTART': '1', 15:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:50 INFO - 'PWD': '/builds/slave/test', 15:26:50 INFO - 'SHELL': '/bin/bash', 15:26:50 INFO - 'SHLVL': '1', 15:26:50 INFO - 'TERM': 'linux', 15:26:50 INFO - 'TMOUT': '86400', 15:26:50 INFO - 'USER': 'cltbld', 15:26:50 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:50 INFO - '_': '/tools/buildbot/bin/python'} 15:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:26:51 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 15:26:51 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 15:26:51 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 15:26:51 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 15:26:51 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 15:26:51 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 15:26:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:26:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:26:53 INFO - Installing collected packages: mozsystemmonitor 15:26:53 INFO - Running setup.py install for mozsystemmonitor 15:26:53 INFO - Successfully installed mozsystemmonitor 15:26:53 INFO - Cleaning up... 15:26:53 INFO - Return code: 0 15:26:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:26:53 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')]} 15:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:53 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')]} 15:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:53 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:26:53 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 15:26:53 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 15:26:53 INFO - Using env: {'DISPLAY': ':0', 15:26:53 INFO - 'HOME': '/home/cltbld', 15:26:53 INFO - 'LANG': 'en_US.UTF-8', 15:26:53 INFO - 'LANGUAGE': 'en_US:en', 15:26:53 INFO - 'LOGNAME': 'cltbld', 15:26:53 INFO - 'MAIL': '/var/mail/cltbld', 15:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:53 INFO - 'MOZ_NO_REMOTE': '1', 15:26:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:53 INFO - 'NO_EM_RESTART': '1', 15:26:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:53 INFO - 'PWD': '/builds/slave/test', 15:26:53 INFO - 'SHELL': '/bin/bash', 15:26:53 INFO - 'SHLVL': '1', 15:26:53 INFO - 'TERM': 'linux', 15:26:53 INFO - 'TMOUT': '86400', 15:26:53 INFO - 'USER': 'cltbld', 15:26:53 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:53 INFO - '_': '/tools/buildbot/bin/python'} 15:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:53 INFO - Downloading/unpacking jsonschema==2.5.1 15:26:53 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 15:26:53 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 15:26:53 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 15:26:53 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 15:26:53 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 15:26:53 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 15:26:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:26:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:26:55 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 15:26:55 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 15:26:55 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 15:26:55 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 15:26:55 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 15:26:55 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 15:26:55 INFO - Downloading functools32-3.2.3-2.tar.gz 15:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:26:55 INFO - warning: no files found matching '*.txt' 15:26:55 INFO - no previously-included directories found matching 'build' 15:26:55 INFO - no previously-included directories found matching 'dist' 15:26:55 INFO - no previously-included directories found matching '.git*' 15:26:55 INFO - Installing collected packages: jsonschema, functools32 15:26:56 INFO - Running setup.py install for functools32 15:26:56 INFO - warning: no files found matching '*.txt' 15:26:56 INFO - no previously-included directories found matching 'build' 15:26:56 INFO - no previously-included directories found matching 'dist' 15:26:56 INFO - no previously-included directories found matching '.git*' 15:26:56 INFO - Successfully installed jsonschema functools32 15:26:56 INFO - Cleaning up... 15:26:56 INFO - Return code: 0 15:26:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:26: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')]} 15:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26: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')]} 15:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:56 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:26:56 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 15:26:56 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 15:26:56 INFO - Using env: {'DISPLAY': ':0', 15:26:56 INFO - 'HOME': '/home/cltbld', 15:26:56 INFO - 'LANG': 'en_US.UTF-8', 15:26:56 INFO - 'LANGUAGE': 'en_US:en', 15:26:56 INFO - 'LOGNAME': 'cltbld', 15:26:56 INFO - 'MAIL': '/var/mail/cltbld', 15:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:56 INFO - 'MOZ_NO_REMOTE': '1', 15:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:56 INFO - 'NO_EM_RESTART': '1', 15:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:56 INFO - 'PWD': '/builds/slave/test', 15:26:56 INFO - 'SHELL': '/bin/bash', 15:26:56 INFO - 'SHLVL': '1', 15:26:56 INFO - 'TERM': 'linux', 15:26:56 INFO - 'TMOUT': '86400', 15:26:56 INFO - 'USER': 'cltbld', 15:26:56 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:56 INFO - '_': '/tools/buildbot/bin/python'} 15:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:26:56 INFO - Cleaning up... 15:26:56 INFO - Return code: 0 15:26:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:26: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')]} 15:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26: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')]} 15:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:56 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:26:56 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 15:26:56 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 15:26:56 INFO - Using env: {'DISPLAY': ':0', 15:26:56 INFO - 'HOME': '/home/cltbld', 15:26:56 INFO - 'LANG': 'en_US.UTF-8', 15:26:56 INFO - 'LANGUAGE': 'en_US:en', 15:26:56 INFO - 'LOGNAME': 'cltbld', 15:26:56 INFO - 'MAIL': '/var/mail/cltbld', 15:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:56 INFO - 'MOZ_NO_REMOTE': '1', 15:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:56 INFO - 'NO_EM_RESTART': '1', 15:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:56 INFO - 'PWD': '/builds/slave/test', 15:26:56 INFO - 'SHELL': '/bin/bash', 15:26:56 INFO - 'SHLVL': '1', 15:26:56 INFO - 'TERM': 'linux', 15:26:56 INFO - 'TMOUT': '86400', 15:26:56 INFO - 'USER': 'cltbld', 15:26:56 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:56 INFO - '_': '/tools/buildbot/bin/python'} 15:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:56 INFO - Downloading/unpacking blobuploader==1.2.4 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:56 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 15:26:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:26:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:26:58 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 15:26:58 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 15:26:58 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 15:26:58 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 15:26:58 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 15:26:58 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 15:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:26:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:26:59 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 15:26:59 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 15:26:59 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 15:26:59 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 15:26:59 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 15:26:59 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 15:26:59 INFO - Downloading docopt-0.6.1.tar.gz 15:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:26:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:26:59 INFO - Running setup.py install for blobuploader 15:26:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:26:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:26:59 INFO - Running setup.py install for requests 15:26:59 INFO - Running setup.py install for docopt 15:27:00 INFO - Successfully installed blobuploader requests docopt 15:27:00 INFO - Cleaning up... 15:27:00 INFO - Return code: 0 15:27:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:00 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')]} 15:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:00 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')]} 15:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:00 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:27:00 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 15:27:00 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 15:27:00 INFO - Using env: {'DISPLAY': ':0', 15:27:00 INFO - 'HOME': '/home/cltbld', 15:27:00 INFO - 'LANG': 'en_US.UTF-8', 15:27:00 INFO - 'LANGUAGE': 'en_US:en', 15:27:00 INFO - 'LOGNAME': 'cltbld', 15:27:00 INFO - 'MAIL': '/var/mail/cltbld', 15:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:00 INFO - 'MOZ_NO_REMOTE': '1', 15:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:00 INFO - 'NO_EM_RESTART': '1', 15:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:00 INFO - 'PWD': '/builds/slave/test', 15:27:00 INFO - 'SHELL': '/bin/bash', 15:27:00 INFO - 'SHLVL': '1', 15:27:00 INFO - 'TERM': 'linux', 15:27:00 INFO - 'TMOUT': '86400', 15:27:00 INFO - 'USER': 'cltbld', 15:27:00 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:00 INFO - '_': '/tools/buildbot/bin/python'} 15:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:27:00 INFO - Running setup.py (path:/tmp/pip-KrmYfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:27:00 INFO - Running setup.py (path:/tmp/pip-jT7yLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:27:00 INFO - Running setup.py (path:/tmp/pip-ZBJfTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:27:00 INFO - Running setup.py (path:/tmp/pip-vBLBMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:27:00 INFO - Running setup.py (path:/tmp/pip-7p4fiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:27:00 INFO - Running setup.py (path:/tmp/pip-Plxbgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:27:00 INFO - Running setup.py (path:/tmp/pip-lMMWrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:27:00 INFO - Running setup.py (path:/tmp/pip-if6Men-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:27:00 INFO - Running setup.py (path:/tmp/pip-UJ3nek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:27:00 INFO - Running setup.py (path:/tmp/pip-7DO_lD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:27:01 INFO - Running setup.py (path:/tmp/pip-VcjVus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:27:01 INFO - Running setup.py (path:/tmp/pip-AcGbyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:27:01 INFO - Running setup.py (path:/tmp/pip-Rma9Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:27:01 INFO - Running setup.py (path:/tmp/pip-hwlo2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:01 INFO - Running setup.py (path:/tmp/pip-u6nhGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:27:01 INFO - Running setup.py (path:/tmp/pip-aTe5Os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:27:01 INFO - Running setup.py (path:/tmp/pip-mRb2yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:27:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:27:01 INFO - Running setup.py install for manifestparser 15:27:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Running setup.py install for mozcrash 15:27:01 INFO - Running setup.py install for mozdebug 15:27:01 INFO - Running setup.py install for mozdevice 15:27:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:27:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for mozfile 15:27:02 INFO - Running setup.py install for mozhttpd 15:27:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for mozinfo 15:27:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for mozInstall 15:27:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for mozleak 15:27:02 INFO - Running setup.py install for mozlog 15:27:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for moznetwork 15:27:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for mozprocess 15:27:02 INFO - Running setup.py install for mozprofile 15:27:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:27:02 INFO - Running setup.py install for mozrunner 15:27:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:27:03 INFO - Running setup.py install for mozscreenshot 15:27:03 INFO - Running setup.py install for moztest 15:27:03 INFO - Running setup.py install for mozversion 15:27:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:27:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:27:03 INFO - Cleaning up... 15:27:03 INFO - Return code: 0 15:27:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:03 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:27:03 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 15:27:03 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 15:27:03 INFO - Using env: {'DISPLAY': ':0', 15:27:03 INFO - 'HOME': '/home/cltbld', 15:27:03 INFO - 'LANG': 'en_US.UTF-8', 15:27:03 INFO - 'LANGUAGE': 'en_US:en', 15:27:03 INFO - 'LOGNAME': 'cltbld', 15:27:03 INFO - 'MAIL': '/var/mail/cltbld', 15:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:03 INFO - 'MOZ_NO_REMOTE': '1', 15:27:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:03 INFO - 'NO_EM_RESTART': '1', 15:27:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:03 INFO - 'PWD': '/builds/slave/test', 15:27:03 INFO - 'SHELL': '/bin/bash', 15:27:03 INFO - 'SHLVL': '1', 15:27:03 INFO - 'TERM': 'linux', 15:27:03 INFO - 'TMOUT': '86400', 15:27:03 INFO - 'USER': 'cltbld', 15:27:03 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:03 INFO - '_': '/tools/buildbot/bin/python'} 15:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:27:03 INFO - Running setup.py (path:/tmp/pip-fSiXpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:27:03 INFO - Running setup.py (path:/tmp/pip-zqaTW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:27:03 INFO - Running setup.py (path:/tmp/pip-nQCLlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:27:03 INFO - Running setup.py (path:/tmp/pip-WnlrdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:27:03 INFO - Running setup.py (path:/tmp/pip-XWfYd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:27:03 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)) 15:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:27:03 INFO - Running setup.py (path:/tmp/pip-BXMhYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:27:04 INFO - Running setup.py (path:/tmp/pip-xQrGFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:27:04 INFO - Running setup.py (path:/tmp/pip-nbSCmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:27:04 INFO - Running setup.py (path:/tmp/pip-bgXIWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:27:04 INFO - Running setup.py (path:/tmp/pip-Q5SvK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:27:04 INFO - Running setup.py (path:/tmp/pip-7_dG0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:27:04 INFO - Running setup.py (path:/tmp/pip-y_uIq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:27:04 INFO - Running setup.py (path:/tmp/pip-uxEvsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:27:04 INFO - Running setup.py (path:/tmp/pip-Bo74VR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:04 INFO - Running setup.py (path:/tmp/pip-B2QEUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:27:04 INFO - Running setup.py (path:/tmp/pip-LCENVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:27:04 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)) 15:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:27:04 INFO - Running setup.py (path:/tmp/pip-wXGFim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:27:04 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)) 15:27:04 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)) 15:27:04 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)) 15:27:04 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)) 15:27:04 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)) 15:27:04 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)) 15:27:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:27:04 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 15:27:04 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 15:27:04 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 15:27:04 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 15:27:04 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 15:27:04 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 15:27:06 INFO - Downloading blessings-1.6.tar.gz 15:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:27:06 INFO - Installing collected packages: blessings 15:27:06 INFO - Running setup.py install for blessings 15:27:07 INFO - Successfully installed blessings 15:27:07 INFO - Cleaning up... 15:27:07 INFO - Return code: 0 15:27:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:27:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:27:07 INFO - Reading from file tmpfile_stdout 15:27:07 INFO - Current package versions: 15:27:07 INFO - argparse == 1.2.1 15:27:07 INFO - blessings == 1.6 15:27:07 INFO - blobuploader == 1.2.4 15:27:07 INFO - docopt == 0.6.1 15:27:07 INFO - functools32 == 3.2.3-2 15:27:07 INFO - jsonschema == 2.5.1 15:27:07 INFO - manifestparser == 1.1 15:27:07 INFO - mozInstall == 1.12 15:27:07 INFO - mozcrash == 1.0 15:27:07 INFO - mozdebug == 0.1 15:27:07 INFO - mozdevice == 0.49 15:27:07 INFO - mozfile == 1.2 15:27:07 INFO - mozhttpd == 0.7 15:27:07 INFO - mozinfo == 0.9 15:27:07 INFO - mozleak == 0.1 15:27:07 INFO - mozlog == 3.4 15:27:07 INFO - moznetwork == 0.27 15:27:07 INFO - mozprocess == 0.25 15:27:07 INFO - mozprofile == 0.28 15:27:07 INFO - mozrunner == 6.13 15:27:07 INFO - mozscreenshot == 0.1 15:27:07 INFO - mozsystemmonitor == 0.3 15:27:07 INFO - moztest == 0.8 15:27:07 INFO - mozversion == 1.4 15:27:07 INFO - psutil == 3.1.1 15:27:07 INFO - requests == 1.2.3 15:27:07 INFO - wsgiref == 0.1.2 15:27:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:07 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:27:07 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 15:27:07 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 15:27:07 INFO - Using env: {'DISPLAY': ':0', 15:27:07 INFO - 'HOME': '/home/cltbld', 15:27:07 INFO - 'LANG': 'en_US.UTF-8', 15:27:07 INFO - 'LANGUAGE': 'en_US:en', 15:27:07 INFO - 'LOGNAME': 'cltbld', 15:27:07 INFO - 'MAIL': '/var/mail/cltbld', 15:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:07 INFO - 'MOZ_NO_REMOTE': '1', 15:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:07 INFO - 'NO_EM_RESTART': '1', 15:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:07 INFO - 'PWD': '/builds/slave/test', 15:27:07 INFO - 'SHELL': '/bin/bash', 15:27:07 INFO - 'SHLVL': '1', 15:27:07 INFO - 'TERM': 'linux', 15:27:07 INFO - 'TMOUT': '86400', 15:27:07 INFO - 'USER': 'cltbld', 15:27:07 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:07 INFO - '_': '/tools/buildbot/bin/python'} 15:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 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)) 15:27:07 INFO - Cleaning up... 15:27:07 INFO - Return code: 0 15:27:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:07 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 0x28981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2990a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b35b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, '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': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', '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 15:27:07 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 15:27:07 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 15:27:07 INFO - Using env: {'DISPLAY': ':0', 15:27:07 INFO - 'HOME': '/home/cltbld', 15:27:07 INFO - 'LANG': 'en_US.UTF-8', 15:27:07 INFO - 'LANGUAGE': 'en_US:en', 15:27:07 INFO - 'LOGNAME': 'cltbld', 15:27:07 INFO - 'MAIL': '/var/mail/cltbld', 15:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:07 INFO - 'MOZ_NO_REMOTE': '1', 15:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:07 INFO - 'NO_EM_RESTART': '1', 15:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:07 INFO - 'PWD': '/builds/slave/test', 15:27:07 INFO - 'SHELL': '/bin/bash', 15:27:07 INFO - 'SHLVL': '1', 15:27:07 INFO - 'TERM': 'linux', 15:27:07 INFO - 'TMOUT': '86400', 15:27:07 INFO - 'USER': 'cltbld', 15:27:07 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:07 INFO - '_': '/tools/buildbot/bin/python'} 15:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:27:07 INFO - Cleaning up... 15:27:07 INFO - Return code: 0 15:27:07 INFO - Running post-action listener: _resource_record_post_action 15:27:07 INFO - Running post-action listener: _start_resource_monitoring 15:27:07 INFO - Starting resource monitoring. 15:27:07 INFO - [mozharness: 2017-04-05 22:27:07.812838Z] Finished create-virtualenv step (success) 15:27:07 INFO - [mozharness: 2017-04-05 22:27:07.813360Z] Running install step. 15:27:07 INFO - Running pre-action listener: _resource_record_pre_action 15:27:07 INFO - Running main action method: install 15:27:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:27:08 INFO - Reading from file tmpfile_stdout 15:27:08 INFO - Detecting whether we're running mozinstall >=1.0... 15:27:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:27:08 INFO - Reading from file tmpfile_stdout 15:27:08 INFO - Output received: 15:27:08 INFO - Usage: mozinstall [options] installer 15:27:08 INFO - Options: 15:27:08 INFO - -h, --help show this help message and exit 15:27:08 INFO - -d DEST, --destination=DEST 15:27:08 INFO - Directory to install application into. [default: 15:27:08 INFO - "/builds/slave/test"] 15:27:08 INFO - --app=APP Application being installed. [default: firefox] 15:27:08 INFO - mkdir: /builds/slave/test/build/application 15:27:08 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'] 15:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:27:24 INFO - Reading from file tmpfile_stdout 15:27:24 INFO - Output received: 15:27:24 INFO - /builds/slave/test/build/application/firefox/firefox 15:27:24 INFO - Running post-action listener: _resource_record_post_action 15:27:24 INFO - [mozharness: 2017-04-05 22:27:24.698674Z] Finished install step (success) 15:27:24 INFO - [mozharness: 2017-04-05 22:27:24.698923Z] Running run-tests step. 15:27:24 INFO - Running pre-action listener: _resource_record_pre_action 15:27:24 INFO - Running main action method: run_tests 15:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:27:24 INFO - Python 2.7.3 15:27:24 INFO - Return code: 0 15:27:24 INFO - grabbing minidump binary from tooltool 15:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:24 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 0x2b33290>, '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 0x2b33b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b34040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:27:24 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 15:27:24 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 15:27:24 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 15:27:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:27:24 INFO - Return code: 0 15:27:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:27:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:27:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:27:24 INFO - ENV: RUST_BACKTRACE is now 1 15:27:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:27:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 15:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 15:27:24 INFO - Using env: {'DISPLAY': ':0', 15:27:24 INFO - 'HOME': '/home/cltbld', 15:27:24 INFO - 'LANG': 'en_US.UTF-8', 15:27:24 INFO - 'LANGUAGE': 'en_US:en', 15:27:24 INFO - 'LOGNAME': 'cltbld', 15:27:24 INFO - 'MAIL': '/var/mail/cltbld', 15:27:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:27:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:24 INFO - 'MOZ_NO_REMOTE': '1', 15:27:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:27:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:24 INFO - 'NO_EM_RESTART': '1', 15:27:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:24 INFO - 'PWD': '/builds/slave/test', 15:27:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:27:24 INFO - 'RUST_BACKTRACE': '1', 15:27:24 INFO - 'SHELL': '/bin/bash', 15:27:24 INFO - 'SHLVL': '1', 15:27:24 INFO - 'TERM': 'linux', 15:27:24 INFO - 'TMOUT': '86400', 15:27:24 INFO - 'USER': 'cltbld', 15:27:24 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387', 15:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:24 INFO - '_': '/tools/buildbot/bin/python'} 15:27:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 15:27:24 INFO - mozversion application_buildid: 20170405213114 15:27:24 INFO - mozversion application_changeset: b95fcbceb5b39ade0054b2fd114c19bc2ed91ef8 15:27:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:27:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:27:24 INFO - mozversion application_name: Firefox 15:27:24 INFO - mozversion application_remotingname: firefox-dev 15:27:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:27:24 INFO - mozversion application_vendor: Mozilla 15:27:24 INFO - mozversion application_version: 54.0a2 15:27:24 INFO - mozversion platform_buildid: 20170405213114 15:27:24 INFO - mozversion platform_changeset: b95fcbceb5b39ade0054b2fd114c19bc2ed91ef8 15:27:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:27:24 INFO - mozversion platform_version: 54.0a2 15:27:24 INFO - using testdate: 1491431244 15:27:24 INFO - actual date: 1491431244 15:27:24 INFO - starting webserver on 'localhost:47437' 15:27:24 INFO - SUITE-START | Running 2 tests 15:27:24 INFO - TEST-START | dromaeo_css 15:27:24 INFO - operating with platform_type : linux_ 15:27:24 INFO - Initialising browser for dromaeo_css test... 15:27:24 INFO - TEST-INFO | started process 23997 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnq_wjr/profile http://localhost:47437/getInfo.html) 15:27:29 INFO - PROCESS | 23997 | __metrics Screen width/height:1600/1200 15:27:29 INFO - PROCESS | 23997 | colorDepth:24 15:27:29 INFO - PROCESS | 23997 | Browser inner width/height: 1024/697 15:27:29 INFO - PROCESS | 23997 | __metrics 15:27:29 INFO - PROCESS | 23997 | 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" 15:27:29 INFO - PROCESS | 23997 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:27:31 INFO - TEST-INFO | 23997: exit 0 15:27:31 INFO - Browser initialized. 15:27:31 INFO - Running cycle 1/1 for dromaeo_css test... 15:27:31 INFO - TEST-INFO | started process 24091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnq_wjr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:27:42 INFO - PROCESS | 24091 | RSS: Main: 139792384 15:27:42 INFO - PROCESS | 24091 | 15:30:43 INFO - PROCESS | 24091 | Cycle 1(1): loaded http://localhost:47437/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47437/tests/dromaeo/cssquery-ext.html) 15:30:45 INFO - PROCESS | 24091 | RSS: Main: 289513472 15:30:45 INFO - PROCESS | 24091 | 15:32:33 INFO - PROCESS | 24091 | ************************* 15:32:33 INFO - PROCESS | 24091 | A coding exception was thrown and uncaught in a Task. 15:32:33 INFO - PROCESS | 24091 | 15:32:33 ERROR - PROCESS | 24091 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:32:33 INFO - PROCESS | 24091 | Full stack: 15:32:33 INFO - PROCESS | 24091 | ************************* 15:32:33 INFO - PROCESS | 24091 | ************************* 15:32:33 INFO - PROCESS | 24091 | A coding exception was thrown and uncaught in a Task. 15:32:33 INFO - PROCESS | 24091 | 15:32:33 ERROR - PROCESS | 24091 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:32:33 INFO - PROCESS | 24091 | Full stack: 15:32:33 INFO - PROCESS | 24091 | ************************* 15:32:33 INFO - PROCESS | 24091 | ************************* 15:32:33 INFO - PROCESS | 24091 | A coding exception was thrown and uncaught in a Task. 15:32:33 INFO - PROCESS | 24091 | 15:32:33 ERROR - PROCESS | 24091 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:32:33 INFO - PROCESS | 24091 | Full stack: 15:32:33 INFO - PROCESS | 24091 | ************************* 15:32:33 INFO - PROCESS | 24091 | ************************* 15:32:33 INFO - PROCESS | 24091 | A coding exception was thrown and uncaught in a Task. 15:32:33 INFO - PROCESS | 24091 | 15:32:33 ERROR - PROCESS | 24091 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:32:33 INFO - PROCESS | 24091 | Full stack: 15:32:33 INFO - PROCESS | 24091 | ************************* 15:33:47 INFO - PROCESS | 24091 | Cycle 1(1): loaded http://localhost:47437/tests/dromaeo/cssquery-ext.html (next: http://localhost:47437/tests/dromaeo/cssquery-jquery.html) 15:33:48 INFO - PROCESS | 24091 | RSS: Main: 223088640 15:33:48 INFO - PROCESS | 24091 | 15:36:50 INFO - PROCESS | 24091 | Cycle 1(1): loaded http://localhost:47437/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47437/tests/dromaeo/cssquery-mootools.html) 15:36:50 INFO - PROCESS | 24091 | RSS: Main: 173113344 15:36:50 INFO - PROCESS | 24091 | 15:39:52 INFO - PROCESS | 24091 | Cycle 1(1): loaded http://localhost:47437/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47437/tests/dromaeo/cssquery-prototype.html) 15:39:53 INFO - PROCESS | 24091 | RSS: Main: 171278336 15:39:53 INFO - PROCESS | 24091 | 15:42:56 INFO - PROCESS | 24091 | Cycle 1(1): loaded http://localhost:47437/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47437/tests/dromaeo/cssquery-yui.html) 15:42:56 INFO - PROCESS | 24091 | RSS: Main: 176816128 15:42:56 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | Cycle 1(1): loaded http://localhost:47437/tests/dromaeo/cssquery-yui.html (next: http://localhost:47437/tests/dromaeo/cssquery-dojo.html) 15:45:59 INFO - PROCESS | 24091 | RSS: Main: 269717504 15:45:59 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | __start_tp_report 15:45:59 INFO - PROCESS | 24091 | _x_x_mozilla_page_load 15:45:59 INFO - PROCESS | 24091 | _x_x_mozilla_page_load_details 15:45:59 INFO - PROCESS | 24091 | |i|pagename|runs| 15:45:59 INFO - PROCESS | 24091 | |0;dojo.html;3271.16585270756;3419.145213696575;3427.6915077643007;3436.742244331676;3463.5497385339904;5660.094384898412;5746.48281654651;5748.25272714547;5756.769729210831;5757.309122905247;5738.254026976487;5759.798407055754;5807.041838096709;5809.419058094199;5809.9709501452435;5125.820596279113;5133.640645154831;5135;5146.3309769729885;5174.301469301652;5074.162763144077;5204.739763011853;5224.242484839696;5225.190095535198;5250.212468129792;5119.206522988943;5128.461511541291;5176.50823171798;5240.266362709223;5258.684478931257;5224.555912747421;5238;5254.605904557155;5255.1854462557985;5259.894802103937;5126.384833819965;5170.844874653766;5212.635115541913;5215.426303106655;5235.371755389339;5568.053430916754;5596.860078498029;5605.439456054402;5640.8307750767135;5646.830595082154;6134.4172303631085;6137.634838194141;6140.631562106243;6141.969290153521;6146.016637338049;6149.422992452974;6186.257649082094;6189.04069869171;6190.535709821781;6191.442770150708;5651.434856514356;5682;5786.71066446676;5795.710214489259;5831.620944638585;6395.296517383084;6398.968005159944;6405.167328247391;6405.615663060231;6408.166938297992;6063.211782468251;6169.753209871645;6175.660338681416;6186.876262474815;6191.938080619227;5940.851478713067;5960.433758792908;6105.8168254952425;6236.939720247568;6270.655113968684;6176.320604733476;6177.938220617736;6180.969095154495;6185.969070154621;6202.038684003896;5997;6048.425399587032;6051.183090282843;6178.196834411498;6190.721417536131;4690.437147542249;4711.2226482629985;4720.811167553259;4731.597814185764;4732.763361831963;4118.629323360912;4235.597618226264;4239.342901850218;4241.27898257297;4282.421873047098;5982.760689572456;6007.669578173155;6044.546659000504;6115.240341084353;6118.174046503754;6236.875262494725;6247.96876015617;6253.5622506425025;6256.217972753406;6261.530385221126;6071.180390647294;6080.0575910733905;6080.787172448943;6086.178365920633;6091.543134264948;5131.179011358165;5176.430592634807;5210.635255532152;5260.132078207053;5311.468853114735;6529.2817790043055;6531.510136739858;6534;6537.378948999933;6539.705713242915;6191.102290167991;6194.4115309045565;6224.470919971672;6246.281677607133;6251.874962500816;6229.937700622936;6272.843178920563;6397.424231819158;6462.289148193601;6528.347165283433;6174.351693072343;6178.783742568989;6186;6186.1648677427975;6187.071939209155;6272.8118156455375;6274.843128921813;6278.560500764903;6279.058141278845;6282.874342513215;5815.9709201453725;5931.7627294907725;6025.367336429612;6087.147799308012;6089.177960975212;6097.603655762361;6107.083937409424;6122.093225952662;6123.130109042633;6123.313124872248;6084.361142080018;6087.208662873798;6109.969450152721;6112.358202388685;6125.540584456095;5928.199693041384;5942.168096466585;6068.059450785136;6071.5446341523675;6130.877382452415;5951.940480595139;5954.07711804671;5955.196048533566;5960.821175364746;5960.910586341295 15:45:59 INFO - PROCESS | 24091 | |1;ext.html;760.1941941541966;817.6892780743317;837.3594200436663;896.5472436419611;910.6493999810072;32272.838635806816;32582;44568.88577785553;45399.546004539945;45406.318905216365;46436.76781616096;46591.76704116483;46617;46700.766496167475;46720.29919551201;7750.689972401097;9297.395669281508;9408.388454750453;9423.48170850603;9432.858507122399;7787.415943804237;10829.620963266287;11000.889991100106;11013.724656883602;11026.338419694805;11106.111511079094;11118;11130.666080017612;11133.053690436323;11156.10751139911;10979.231453798197;10984.780304393948;11013;11014.614488492944;11031.117510599133;10755.731106722316;10812.72968175794;10832.241743077988;10840.078593319577;10865.51105200268;43294.13411731779;43320.13359732819;43377.56622433767;43438.565614343766;43511.34732979008;4181.498220213563;4187.72779769314;4228.513720922103;4308.892277693051;4336.3712261722185;29208.41583168346;29646.258843529085;29697.55453668175;30114;30960.380792384025;4246.766427846467;4325.156594464081;4339.088791353819;4343.9348409773875;4429.822807087713;236960;237859;238097;238492;238790.99999999825;55100.448995510334;55113.44886551164;55128.448715513136;55185.172222416695;55292.170617440774;57923.71038144867;58057.419425806045;58096.709516452145;58216.41783582194;58285.125723114186;16189;16635.66728665444;16647.16764161787;16705.248263827914;16718.164091795363;13028.283444410648;13216.867831321755;14881.404743810126;14882.702345953021;14887.18120503361;13256.138351007347;13551.25468099264;13778.379972901244;13810;13962.790558141636;5400.729963501888;5416.2688037114485;5433.843110020837;5603.467670571371;5650.180723795027;938.8169306985072;949.7435692363023;953.2897990996752;955.3742298794301;972.7957129002847;27032.324191894968;28628.14115576536;28845.134645960654;29788.40423191524;31026.379472410426;1133.8072527670315;1137.1528211482498;1154.7921374152734;1155.526234243956;1173.372245848467;15207.467738629095;15213.923930380499;15238.542843714706;15376.923115384352;15457.227138643248;326267;329633;347531;438404;441252;52887.73556132195;53039.20441193385;53158;53177.202341964905;53193.2021019685;1490.6944076464515;1501.8648321651103;1512.8108986376483;1515.727169109571;1519.270750039987;27204;28593.428131436838;28624.7081585612;28973.855130724212;29064.418711626073;38056.80971595124;38367.61632383752;38442;38454.80772596119;38515;308314;310246;310773;311070;311874;24675.8766206175;25550.105746299534;28371.574426384035;29268.512176464388;29719.405611888073;38882.22235555216;38914;38932.41601375925;39170.2165956685;39369.212615746954;30034;30274.54588181135;30392.848035759678;30529.694703052686;30776.69223307738;29743.851280743456;29960.550591740706;30182.698173018867;30344.241393965327;30581.23546911345;34355.312893742484;34632.65367346294;34936.47595286022;35025;36549;30464.695353046187;30760.230994225323;30841.383172336875;30896;31000.379992400478;24293.75706242915;25209.495810084063;25519.36201594975;25523.36191595225;25725.1620833914 15:45:59 INFO - PROCESS | 24091 | |2;jquery.html;458.4086528378392;461.6814398065335;475.54347826086956;478.50952773407266;484.9684770489918;19922.7011594826;21261.25585604504;21506.677399838976;21827.36294777892;21997.780022199775;22276;22447.775522244814;22609;22739.772602273973;22912.198073067448;6775;7060.364567188952;7457.254274572527;7701.6919323227;7745.109312429058;7564.697412103509;7717.807054823618;7783.727569535067;7787.415943804209;7787.649555770004;7814.960925195366;7817.0619525657;7822.335101516378;7826.608669566499;7833.804154896116;7591.924080759177;7817.101033381177;7873.921260787376;7884.881726774103;7886.171951945049;7417;7448.776536703854;7504.737334193277;7507.249275072503;7649.235076492362;25419.61870571943;25721.228363149134;26052.34869128268;26127.869360653265;26247.475050499073;2937.251000994754;3031.227037105546;3070.1250143708935;3131.827749473778;3157.700018498239;15711.764323535157;16502.25739841698;16667.749983750255;16752.66494670112;16958.830411695973;2278.2481781012225;2345.155743932183;2362.9527409451716;2403.2429784617966;2408.5182963407387;123843;126453.36773316166;127293.36353318267;127316.36341818325;128361;17871.73192402115;18429.723554146698;18687.626247474975;18909.52726181829;19214.903925480285;33437;33613.66386336106;33668.32663346719;33796.66203337984;33877.153071173416;10399.48002599867;10416.479176041168;10535.789284214427;10670.62652807148;10775.137988960865;8720.8691869622;8887.711281864093;8899.688510902088;9048.276137908952;9084.364094513452;8934.329925255632;9349.859752103725;9567.952160239154;9691.27315451344;9772.95113524442;3514.38498262803;3621.8732344367822;3758.4778164843283;3760.7555508891833;3820.541535015816;965.8502932045403;972.3096601412934;975.1662328708993;984.9113579777854;989.618996686281;20700.275490357766;21676.34970950874;21679;22457.55084898293;22744.51321054862;1082.1288862465633;1085.316250762015;1097.939613321275;1113.8886111388958;1130.496928866663;11378.089752819755;11583.247088939192;11654.650360489199;11689.473973671205;12414.131010829155;682102;689441.4880389974;695860;695887;702637;118119;122328;122601;124957;125182.37408812897;1329.8257349234073;1338.4110991164052;1358.4226703651107;1370.6573356660836;1373.986260137406;18883.236614801754;19716.50708732293;19760.40718778439;19846.801531984496;19858.304959326353;52987;53570;53828.730856345464;54149.45850541444;54807.451925480236;71769.64115179391;72263.47341078163;72376.63811680907;73845.6307718458;75032.2496775047;10709.607750992322;12102.818457722966;12112.697182570506;12280.078994075659;12956.481740730642;12673.873261267268;13797.655058623213;14013.789793153315;14417.207053612152;14573.562793116223;11508.36703981289;11534.538618455168;11674.883251167379;11828.467718952495;11951.342676152548;11193.328400296008;11299.887001129884;11306.4912078955;11340.886591133984;11367.715807104558;11899.405029748305;12354;12472.376381181086;12499.812502812647;12593.244405335709;11905.52377904874;11908.285502869856;12063.457144428698;12089.57686480969;12148.817767733315;11856.585019524275;11940.402979851146;12775.23348599087;12868.356582171042;12957.676058098623 15:45:59 INFO - PROCESS | 24091 | |3;mootools.html;1475.6753514226866;1536.5774412036687;1573.1819453883982;1583.3824808324753;1592.0686398456903;9702.805943881118;11147.554097836088;11489.59786407476;11768.705782355446;11878.168528203028;11463.713407164825;11555.19113662044;11565.942170289158;11583;11588.478518466669;2078.968815467769;2145.95708085839;2470.160145550508;2516.1319344826006;2518.320053585531;2483.552960467115;2545.1346542175656;2559.462512872299;2575.304667739709;2576.1498705427166;2423.612222044473;2459.5449841779327;2479.2438306316567;2498.7001559812848;2509.8619575923317;2438.5854404751235;2440.0849681369486;2460.6186041163646;2462.8768561571937;2472.245964980689;2372.181597348912;2373.133806160756;2389.581823180954;2392.5454163709014;2397.460571371445;11799.997000254989;11843.644690659294;11943.522259109712;11944.64166075019;11956.163068585203;5846.298444186682;5985.610935289193;5985.700714964234;5991.041433370639;6003.7898673546215;3498.265364273506;3532.346515894568;3581.713462922986;3594.3170797548382;3609.0796846803896;1293.172369683394;1319.0634649398937;1325.7083441642824;1327.1771501669027;1410.654389674526;16745.413910513194;17935.73096403555;17957.551061223443;17985.640287194314;18004.54988625282;4843.806247750051;4937.925931111036;4997.650164488451;5052.646314758005;5059.114654935372;13168.73662526744;13289.601311960656;13313.866861331457;13318.267495287851;13326.800097998537;6135.785247516315;6141.048137538689;6145.539084568675;6145.539084568675;6150.169727086786;4337.978310108492;5172.482751724798;5446.8910621787345;5671.886562268732;5678.659280443187;4037.555868854424;4112.424260603518;4176.2691528982305;4185.267578173807;4211.241976444208;1065.3980501016902;1071.8767341755643;1082.8050950828926;1087.2823936202226;1097.8353247012851;1070.3043022035738;1093.5899037860802;1098.2147764348529;1102.9007389334997;1107.9113670906308;7435.962820185864;7527.661255243527;7548.207438218908;7675.53946763196;7738.303552680286;984.7242772023844;1015.355249416611;1043.3218408034686;1043.3635482355758;1048.6801525534786;4543.227651299221;4638.373819534387;4640.280756482751;4812.51874812516;4962.404511458576;14604.269786510631;15282.770758438632;15293.082415055133;15317.846821531642;15321.310541025681;14222.644433889029;14762.40950361995;14770.852291477162;14787.852121478863;15120.243987800566;10840.241183117107;11037.448127593589;11049.889501105046;11054.778904421866;11075.446227688582;7472.065991751031;7597.274565103319;7850.842983140419;7890.092639346549;7928.682852686059;13163.47346106145;13355.465781368637;13365.198088114745;13379;13443.058985871286;13568.79646805279;13684.178949263076;13708.24604646754;13720.245386503439;13869.861301386856;8687.174718401742;8933.865992010255;9643.469609171563;9980.950095249478;10190.694279171637;11212.55149793999;11269.887301127212;11333.093352531943;11344.262622929484;11370.374629395463;8875.866861996947;8879.467231966102;8973.461592304482;9206.539673016456;9213.447193168431;9067.954660226656;9265.027172146827;9306.255499559884;9309.069093090637;9473.100055494717;8625;8732;8800.823983520166;8805.515696636747;8825.646974120964;6548.214214294315;6585;6627.1716035495565;6639.3692599203;6704.966475167788;6558.606483610999;6578.76974305897;6618.536702430783;6626.6686665667485;6783.18601767791 15:45:59 INFO - PROCESS | 24091 | |4;prototype.html;421.55525920154236;426.3519450435338;428.61210604403027;434.1534008683067;436.8471035137702;5156.200788877721;5156.278121063054;5221.765020574074;5262.500062494062;5265.946810637869;5081.6696914700515;5111.182210846266;5119.488051194888;5132.615053870956;5133.871653208672;4188.2251783420015;4276.978615106936;4287.635550978171;4288.421063156473;4295.140971805636;4297.269464191079;4347.586979236968;4369.4756629204385;4379.671524635649;4379.912401751963;4340.631046361063;4353.28170851809;4384.868453946371;4389.407429996957;4403.295472724365;4109.97945010276;4260.997331254305;4276.593723596254;4377.540358262384;4400.229959757062;3987;4006.696149713897;4022.839086436539;4046.5751096134777;4051.453053837753;4977.676451030671;5006.524380183877;5018.569707633344;5019.949800501985;5020.623453241022;4915.754212289407;4933.432655244621;5011.974940125276;5028.555439523938;5034.697918124888;5331.866703332409;5339.67961922282;5353.705546194958;5357.249985002103;5364.566677326514;4521.231390663595;4521.711312275986;4537.185125949613;4594.264917613199;4628.847585872585;5390.735890925821;5597.328320601513;5599.880023995217;5651.547876169897;5757.309122905236;4624.65315101362;4629.782301300631;4632.397788287569;4642.419697537808;4653.255479123324;4703.929441058387;4712.811487540528;4713.552212539803;4732;4732.479427262998;4905.48492408292;5009.273655319958;5039.571636410873;5044.899102017939;5087.669301495391;5353.491418315331;5362.51737343641;5373.919391209135;5388.272583201296;5411.079066931262;1616.7413213885723;1624.7400415933394;1628.8696904247868;1637.0914142650704;1640.3028712797013;1184.3190165654783;1255.0712472770085;1258.9559365422167;1268.213707501355;1281.39774306076;5274.894502109936;5299.735013249399;5315.627906046617;5316.08620896477;5340.893182136336;5461.968607422839;5469.562435005269;5491.533219676292;5493.395726470085;5514.972425137848;4877.000214955997;5314.787408503694;5321.3348331458565;5322.168039011905;5329.333833270841;2905.709429057077;2914.591957126004;2914.810537315068;2930.3113768264443;2940.8529573521237;5435.809746658848;5681.233033540502;5755.942440575541;5781.913271300934;5806.21616081832;5381.838544843661;5604.663720176803;5605.80379686709;5660.999999999917;5679.1258884568;5588.469095435927;5631.802886898939;5784.71076446176;5806.506446951972;5815.883682326415;5267.868303292295;5412.648177868426;5419.349678038503;5489.807856725155;5505.58653634749;5308.416074231909;5349.411564727799;5450.691833959901;5491.478309560585;5512.862178445571;5755.683437410982;5837.211976383303;5837.548229743433;5855.765769369177;5862.355140934408;4495.25828238337;4674.270519907656;4689.296605509201;4719.8820029498165;4760.428748550196;5487.835364939058;5557.833265002057;5570.938752661541;5603.719814009365;5609.915851262316;5418.45815418455;5422.5119739223655;5465;5473.042217611823;5481.835544933659;5285.339332583427;5406.675599463887;5407.513323800877;5458.6724796512335;5470.398256191896;5410.48600382963;5411.269478620336;5420.620556561003;5435.918461223164;5453.145930383688;5300.231466437422;5425.348958125051;5441.775600489858;5463.399026107046;5516.3380394352935;5350.892982140257;5351.127105513039;5366.436524164907;5391.81128660495;5405.702686352289 15:45:59 INFO - PROCESS | 24091 | |5;yui.html;849.3035710717206;860.8837806896067;898.3935843305767;914.9999999999999;928.1600151862558;15900.20498975051;16795.412160574368;17522.561935951606;18090.185941632626;18361.7245741314;17680.734788978174;17913.552161195945;18844.152013159408;18960.336388226417;19028.61942761144;872.7163671806657;873.5981448533666;883.5184824270788;890.1943740914483;890.9643614255455;866.0040952904179;875.5753459572103;876.8903887014123;878.2885862451403;879.0286733159862;930.7719608695879;932.9253659707207;933.9392939458913;934.1312579301247;937.1003836317144;930.33481061041;931.8881734191906;932.4498545857977;932.7388331267222;933.1881263300904;687.6355531568277;687.845234822161;688.0092666560188;688.0985908459884;688.3048121397375;16661.750073748903;16771.2452939618;17074.829251707448;18074.186661600128;18236.45290641283;16138.677226455406;16318.112815596056;16423.83576164235;16444;16706.99758014523;13272.668183295398;13440.798388024188;13452.461901524026;13596.456141754317;13597.592072237849;3878.592747761501;3939.2909276330247;3951.1900060487533;3964.1477082427127;4031.677465802729;85565;85678.14321856826;85875.57062214712;88192;89344;12374.257544547356;12459.190152640049;12524.937375313248;12562.497500100077;12579.182353147016;21760.45598860041;21794.782052179275;21798.564028719655;21803.236886708888;21944;8319.958400208081;8393.496390216607;8404.705835295736;8585.570721463902;8629.568521573896;4729.314249433813;4881.072596206709;4884.3650325457465;4972.303877457159;4985.950140498621;3682.7237957152784;3786.4888240087785;3791.014336272534;3826.9617303827163;3838.1556057667267;660.1484085529613;660.2802944790202;661.3386613386614;665.1851481934688;685.6263336481629;828.5955305756685;832.6710949175163;847.4195176304252;870.8127752533236;882.6292956958093;12215.389230538438;12478.313692746986;12486.250824950532;12777.361131943366;12842;675.4393853102011;693.9548929319578;704.2183176673888;721.4444877444339;866.5190819095336;5285.31290932184;5626.767106590828;5691.288588926384;5703;5718.1136923776885;78846;81143;81235;82067;83461.58269208737;56722.71638641781;68046;69692.30307696859;70472.64763676148;70709.64645176742;59830.40169598248;59982.40017599768;60821.695891520256;61545.692271538355;61767.38232617706;11822.29066255993;12412.379381031093;12491.812622810483;12497.750044998867;12575.384079081769;41650;41706.58293417027;42498.575014249465;42807.57192428036;43347.783261083496;52761.7361913188;53335.733321334694;55805;56541.71729141328;59466;12630.179038362478;12682.619521414372;13021.934890325489;13182.472701092232;13290.269035203157;16336.019838809709;16942.57643558921;17117.22972466217;17118.74321885148;17968.01175935336;14978;15480.225988700295;16071.517854464384;16183;16611.41860034893;14646.414143434144;14950.925245373703;15038.849611504183;15513.844861551692;15625.68748625044;15577.766333505233;15919.761203582188;15925.362985480451;15976.840231598;16643.3342666292;11423.200375973602;11511.30932144074;11569.19015668895;11844.644660660195;12103.878961210276;12264.202826816228;12560.874391255971;12620.242785432903;12719.427625756678;12863 15:45:59 INFO - PROCESS | 24091 | __end_tp_report 15:45:59 INFO - PROCESS | 24091 | __start_cc_report 15:45:59 INFO - PROCESS | 24091 | _x_x_mozilla_cycle_collect,1490 15:45:59 INFO - PROCESS | 24091 | __end_cc_report 15:45:59 INFO - PROCESS | 24091 | __startTimestamp1491432359582__endTimestamp 15:45:59 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | ------- Summary: start ------- 15:45:59 INFO - PROCESS | 24091 | Number of tests: 6 15:45:59 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | [#0] dojo.html Cycles:165 Average:5752.75 Median:6051.18 stddev:671.82 (11.1%) stddev-sans-first:645.06 15:45:59 INFO - PROCESS | 24091 | Values: 3271.2 3419.1 3427.7 3436.7 3463.5 5660.1 5746.5 5748.3 5756.8 5757.3 5738.3 5759.8 5807.0 5809.4 5810.0 5125.8 5133.6 5135.0 5146.3 5174.3 5074.2 5204.7 5224.2 5225.2 5250.2 5119.2 5128.5 5176.5 5240.3 5258.7 5224.6 5238.0 5254.6 5255.2 5259.9 5126.4 5170.8 5212.6 5215.4 5235.4 5568.1 5596.9 5605.4 5640.8 5646.8 6134.4 6137.6 6140.6 6142.0 6146.0 6149.4 6186.3 6189.0 6190.5 6191.4 5651.4 5682.0 5786.7 5795.7 5831.6 6395.3 6399.0 6405.2 6405.6 6408.2 6063.2 6169.8 6175.7 6186.9 6191.9 5940.9 5960.4 6105.8 6236.9 6270.7 6176.3 6177.9 6181.0 6186.0 6202.0 5997.0 6048.4 6051.2 6178.2 6190.7 4690.4 4711.2 4720.8 4731.6 4732.8 4118.6 4235.6 4239.3 4241.3 4282.4 5982.8 6007.7 6044.5 6115.2 6118.2 6236.9 6248.0 6253.6 6256.2 6261.5 6071.2 6080.1 6080.8 6086.2 6091.5 5131.2 5176.4 5210.6 5260.1 5311.5 6529.3 6531.5 6534.0 6537.4 6539.7 6191.1 6194.4 6224.5 6246.3 6251.9 6229.9 6272.8 6397.4 6462.3 6528.3 6174.4 6178.8 6186.0 6186.2 6187.1 6272.8 6274.8 6278.6 6279.1 6282.9 5816.0 5931.8 6025.4 6087.1 6089.2 6097.6 6107.1 6122.1 6123.1 6123.3 6084.4 6087.2 6110.0 6112.4 6125.5 5928.2 5942.2 6068.1 6071.5 6130.9 5951.9 5954.1 5955.2 5960.8 5960.9 15:45:59 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | [#1] ext.html Cycles:180 Average:47118.96 Median:30868.69 stddev:82743.47 (268.0%) stddev-sans-first:82902.37 15:45:59 INFO - PROCESS | 24091 | Values: 760.2 817.7 837.4 896.5 910.6 32272.8 32582.0 44568.9 45399.5 45406.3 46436.8 46591.8 46617.0 46700.8 46720.3 7750.7 9297.4 9408.4 9423.5 9432.9 7787.4 10829.6 11000.9 11013.7 11026.3 11106.1 11118.0 11130.7 11133.1 11156.1 10979.2 10984.8 11013.0 11014.6 11031.1 10755.7 10812.7 10832.2 10840.1 10865.5 43294.1 43320.1 43377.6 43438.6 43511.3 4181.5 4187.7 4228.5 4308.9 4336.4 29208.4 29646.3 29697.6 30114.0 30960.4 4246.8 4325.2 4339.1 4343.9 4429.8 236960.0 237859.0 238097.0 238492.0 238791.0 55100.4 55113.4 55128.4 55185.2 55292.2 57923.7 58057.4 58096.7 58216.4 58285.1 16189.0 16635.7 16647.2 16705.2 16718.2 13028.3 13216.9 14881.4 14882.7 14887.2 13256.1 13551.3 13778.4 13810.0 13962.8 5400.7 5416.3 5433.8 5603.5 5650.2 938.8 949.7 953.3 955.4 972.8 27032.3 28628.1 28845.1 29788.4 31026.4 1133.8 1137.2 1154.8 1155.5 1173.4 15207.5 15213.9 15238.5 15376.9 15457.2 326267.0 329633.0 347531.0 438404.0 441252.0 52887.7 53039.2 53158.0 53177.2 53193.2 1490.7 1501.9 1512.8 1515.7 1519.3 27204.0 28593.4 28624.7 28973.9 29064.4 38056.8 38367.6 38442.0 38454.8 38515.0 308314.0 310246.0 310773.0 311070.0 311874.0 24675.9 25550.1 28371.6 29268.5 29719.4 38882.2 38914.0 38932.4 39170.2 39369.2 30034.0 30274.5 30392.8 30529.7 30776.7 29743.9 29960.6 30182.7 30344.2 30581.2 34355.3 34632.7 34936.5 35025.0 36549.0 30464.7 30760.2 30841.4 30896.0 31000.4 24293.8 25209.5 25519.4 25523.4 25725.2 15:45:59 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | [#2] jquery.html Cycles:180 Average:39645.34 Median:22533.28 stddev:114501.23 (508.1%) stddev-sans-first:114784.63 15:45:59 INFO - PROCESS | 24091 | Values: 458.4 461.7 475.5 478.5 485.0 19922.7 21261.3 21506.7 21827.4 21997.8 22276.0 22447.8 22609.0 22739.8 22912.2 6775.0 7060.4 7457.3 7701.7 7745.1 7564.7 7717.8 7783.7 7787.4 7787.6 7815.0 7817.1 7822.3 7826.6 7833.8 7591.9 7817.1 7873.9 7884.9 7886.2 7417.0 7448.8 7504.7 7507.2 7649.2 25419.6 25721.2 26052.3 26127.9 26247.5 2937.3 3031.2 3070.1 3131.8 3157.7 15711.8 16502.3 16667.7 16752.7 16958.8 2278.2 2345.2 2363.0 2403.2 2408.5 123843.0 126453.4 127293.4 127316.4 128361.0 17871.7 18429.7 18687.6 18909.5 19214.9 33437.0 33613.7 33668.3 33796.7 33877.2 10399.5 10416.5 10535.8 10670.6 10775.1 8720.9 8887.7 8899.7 9048.3 9084.4 8934.3 9349.9 9568.0 9691.3 9773.0 3514.4 3621.9 3758.5 3760.8 3820.5 965.9 972.3 975.2 984.9 989.6 20700.3 21676.3 21679.0 22457.6 22744.5 1082.1 1085.3 1097.9 1113.9 1130.5 11378.1 11583.2 11654.7 11689.5 12414.1 682102.0 689441.5 695860.0 695887.0 702637.0 118119.0 122328.0 122601.0 124957.0 125182.4 1329.8 1338.4 1358.4 1370.7 1374.0 18883.2 19716.5 19760.4 19846.8 19858.3 52987.0 53570.0 53828.7 54149.5 54807.5 71769.6 72263.5 72376.6 73845.6 75032.2 10709.6 12102.8 12112.7 12280.1 12956.5 12673.9 13797.7 14013.8 14417.2 14573.6 11508.4 11534.5 11674.9 11828.5 11951.3 11193.3 11299.9 11306.5 11340.9 11367.7 11899.4 12354.0 12472.4 12499.8 12593.2 11905.5 11908.3 12063.5 12089.6 12148.8 11856.6 11940.4 12775.2 12868.4 12957.7 15:45:59 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | [#3] mootools.html Cycles:180 Average:7309.39 Median:2461.75 stddev:4687.74 (190.4%) stddev-sans-first:4680.39 15:45:59 INFO - PROCESS | 24091 | Values: 1475.7 1536.6 1573.2 1583.4 1592.1 9702.8 11147.6 11489.6 11768.7 11878.2 11463.7 11555.2 11565.9 11583.0 11588.5 2079.0 2146.0 2470.2 2516.1 2518.3 2483.6 2545.1 2559.5 2575.3 2576.1 2423.6 2459.5 2479.2 2498.7 2509.9 2438.6 2440.1 2460.6 2462.9 2472.2 2372.2 2373.1 2389.6 2392.5 2397.5 11800.0 11843.6 11943.5 11944.6 11956.2 5846.3 5985.6 5985.7 5991.0 6003.8 3498.3 3532.3 3581.7 3594.3 3609.1 1293.2 1319.1 1325.7 1327.2 1410.7 16745.4 17935.7 17957.6 17985.6 18004.5 4843.8 4937.9 4997.7 5052.6 5059.1 13168.7 13289.6 13313.9 13318.3 13326.8 6135.8 6141.0 6145.5 6145.5 6150.2 4338.0 5172.5 5446.9 5671.9 5678.7 4037.6 4112.4 4176.3 4185.3 4211.2 1065.4 1071.9 1082.8 1087.3 1097.8 1070.3 1093.6 1098.2 1102.9 1107.9 7436.0 7527.7 7548.2 7675.5 7738.3 984.7 1015.4 1043.3 1043.4 1048.7 4543.2 4638.4 4640.3 4812.5 4962.4 14604.3 15282.8 15293.1 15317.8 15321.3 14222.6 14762.4 14770.9 14787.9 15120.2 10840.2 11037.4 11049.9 11054.8 11075.4 7472.1 7597.3 7850.8 7890.1 7928.7 13163.5 13355.5 13365.2 13379.0 13443.1 13568.8 13684.2 13708.2 13720.2 13869.9 8687.2 8933.9 9643.5 9981.0 10190.7 11212.6 11269.9 11333.1 11344.3 11370.4 8875.9 8879.5 8973.5 9206.5 9213.4 9068.0 9265.0 9306.3 9309.1 9473.1 8625.0 8732.0 8800.8 8805.5 8825.6 6548.2 6585.0 6627.2 6639.4 6705.0 6558.6 6578.8 6618.5 6626.7 6783.2 15:45:59 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | [#4] prototype.html Cycles:180 Average:4726.14 Median:5266.91 stddev:1251.45 (23.8%) stddev-sans-first:1212.53 15:45:59 INFO - PROCESS | 24091 | Values: 421.6 426.4 428.6 434.2 436.8 5156.2 5156.3 5221.8 5262.5 5265.9 5081.7 5111.2 5119.5 5132.6 5133.9 4188.2 4277.0 4287.6 4288.4 4295.1 4297.3 4347.6 4369.5 4379.7 4379.9 4340.6 4353.3 4384.9 4389.4 4403.3 4110.0 4261.0 4276.6 4377.5 4400.2 3987.0 4006.7 4022.8 4046.6 4051.5 4977.7 5006.5 5018.6 5019.9 5020.6 4915.8 4933.4 5012.0 5028.6 5034.7 5331.9 5339.7 5353.7 5357.2 5364.6 4521.2 4521.7 4537.2 4594.3 4628.8 5390.7 5597.3 5599.9 5651.5 5757.3 4624.7 4629.8 4632.4 4642.4 4653.3 4703.9 4712.8 4713.6 4732.0 4732.5 4905.5 5009.3 5039.6 5044.9 5087.7 5353.5 5362.5 5373.9 5388.3 5411.1 1616.7 1624.7 1628.9 1637.1 1640.3 1184.3 1255.1 1259.0 1268.2 1281.4 5274.9 5299.7 5315.6 5316.1 5340.9 5462.0 5469.6 5491.5 5493.4 5515.0 4877.0 5314.8 5321.3 5322.2 5329.3 2905.7 2914.6 2914.8 2930.3 2940.9 5435.8 5681.2 5755.9 5781.9 5806.2 5381.8 5604.7 5605.8 5661.0 5679.1 5588.5 5631.8 5784.7 5806.5 5815.9 5267.9 5412.6 5419.3 5489.8 5505.6 5308.4 5349.4 5450.7 5491.5 5512.9 5755.7 5837.2 5837.5 5855.8 5862.4 4495.3 4674.3 4689.3 4719.9 4760.4 5487.8 5557.8 5570.9 5603.7 5609.9 5418.5 5422.5 5465.0 5473.0 5481.8 5285.3 5406.7 5407.5 5458.7 5470.4 5410.5 5411.3 5420.6 5435.9 5453.1 5300.2 5425.3 5441.8 5463.4 5516.3 5350.9 5351.1 5366.4 5391.8 5405.7 15:45:59 INFO - PROCESS | 24091 | 15:45:59 INFO - PROCESS | 24091 | [#5] yui.html Cycles:180 Average:18667.25 Median:41678.29 stddev:22888.66 (54.9%) stddev-sans-first:22913.76 15:45:59 INFO - PROCESS | 24091 | Values: 849.3 860.9 898.4 915.0 928.2 15900.2 16795.4 17522.6 18090.2 18361.7 17680.7 17913.6 18844.2 18960.3 19028.6 872.7 873.6 883.5 890.2 891.0 866.0 875.6 876.9 878.3 879.0 930.8 932.9 933.9 934.1 937.1 930.3 931.9 932.4 932.7 933.2 687.6 687.8 688.0 688.1 688.3 16661.8 16771.2 17074.8 18074.2 18236.5 16138.7 16318.1 16423.8 16444.0 16707.0 13272.7 13440.8 13452.5 13596.5 13597.6 3878.6 3939.3 3951.2 3964.1 4031.7 85565.0 85678.1 85875.6 88192.0 89344.0 12374.3 12459.2 12524.9 12562.5 12579.2 21760.5 21794.8 21798.6 21803.2 21944.0 8320.0 8393.5 8404.7 8585.6 8629.6 4729.3 4881.1 4884.4 4972.3 4986.0 3682.7 3786.5 3791.0 3827.0 3838.2 660.1 660.3 661.3 665.2 685.6 828.6 832.7 847.4 870.8 882.6 12215.4 12478.3 12486.3 12777.4 12842.0 675.4 694.0 704.2 721.4 866.5 5285.3 5626.8 5691.3 5703.0 5718.1 78846.0 81143.0 81235.0 82067.0 83461.6 56722.7 68046.0 69692.3 70472.6 70709.6 59830.4 59982.4 60821.7 61545.7 61767.4 11822.3 12412.4 12491.8 12497.8 12575.4 41650.0 41706.6 42498.6 42807.6 43347.8 52761.7 53335.7 55805.0 56541.7 59466.0 12630.2 12682.6 13021.9 13182.5 13290.3 16336.0 16942.6 17117.2 17118.7 17968.0 14978.0 15480.2 16071.5 16183.0 16611.4 14646.4 14950.9 15038.8 15513.8 15625.7 15577.8 15919.8 15925.4 15976.8 16643.3 11423.2 11511.3 11569.2 11844.6 12103.9 12264.2 12560.9 12620.2 12719.4 12863.0 15:45:59 INFO - PROCESS | 24091 | -------- Summary: end -------- 15:45:59 INFO - PROCESS | 24091 | 15:46:00 INFO - TEST-INFO | 24091: exit 0 15:46:00 INFO - TEST-OK | dromaeo_css | took 1115445ms 15:46:00 INFO - TEST-START | kraken 15:46:00 INFO - operating with platform_type : linux_ 15:46:00 INFO - Initialising browser for kraken test... 15:46:00 INFO - TEST-INFO | started process 24417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXgMhFE/profile http://localhost:47437/getInfo.html) 15:46:04 INFO - PROCESS | 24417 | __metrics Screen width/height:1600/1200 15:46:04 INFO - PROCESS | 24417 | colorDepth:24 15:46:04 INFO - PROCESS | 24417 | Browser inner width/height: 1024/697 15:46:04 INFO - PROCESS | 24417 | __metrics 15:46:05 INFO - PROCESS | 24417 | 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" 15:46:05 INFO - PROCESS | 24417 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:46:06 INFO - TEST-INFO | 24417: exit 0 15:46:06 INFO - Browser initialized. 15:46:06 INFO - Running cycle 1/1 for kraken test... 15:46:06 INFO - TEST-INFO | started process 24511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXgMhFE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:46:18 INFO - PROCESS | 24511 | RSS: Main: 140144640 15:46:18 INFO - PROCESS | 24511 | 15:46:20 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47437/tests/kraken/driver.html?testName=audio-beat-detection) 15:46:21 INFO - PROCESS | 24511 | RSS: Main: 184299520 15:46:21 INFO - PROCESS | 24511 | 15:46:24 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47437/tests/kraken/driver.html?testName=audio-dft) 15:46:24 INFO - PROCESS | 24511 | RSS: Main: 173801472 15:46:24 INFO - PROCESS | 24511 | 15:46:27 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47437/tests/kraken/driver.html?testName=audio-fft) 15:46:28 INFO - PROCESS | 24511 | RSS: Main: 266366976 15:46:28 INFO - PROCESS | 24511 | 15:46:30 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47437/tests/kraken/driver.html?testName=audio-oscillator) 15:46:30 INFO - PROCESS | 24511 | RSS: Main: 172519424 15:46:30 INFO - PROCESS | 24511 | 15:46:32 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47437/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:46:33 INFO - PROCESS | 24511 | RSS: Main: 175570944 15:46:33 INFO - PROCESS | 24511 | 15:46:36 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47437/tests/kraken/driver.html?testName=imaging-darkroom) 15:46:36 INFO - PROCESS | 24511 | RSS: Main: 214155264 15:46:36 INFO - PROCESS | 24511 | 15:46:40 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47437/tests/kraken/driver.html?testName=imaging-desaturate) 15:46:40 INFO - PROCESS | 24511 | RSS: Main: 213180416 15:46:40 INFO - PROCESS | 24511 | 15:46:43 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47437/tests/kraken/driver.html?testName=json-parse-financial) 15:46:44 INFO - PROCESS | 24511 | RSS: Main: 210698240 15:46:44 INFO - PROCESS | 24511 | 15:46:45 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47437/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:46:46 INFO - PROCESS | 24511 | RSS: Main: 173301760 15:46:46 INFO - PROCESS | 24511 | 15:46:48 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47437/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:46:48 INFO - PROCESS | 24511 | RSS: Main: 179875840 15:46:48 INFO - PROCESS | 24511 | 15:46:50 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47437/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:46:51 INFO - PROCESS | 24511 | RSS: Main: 189861888 15:46:51 INFO - PROCESS | 24511 | 15:46:53 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47437/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:46:54 INFO - PROCESS | 24511 | RSS: Main: 198680576 15:46:54 INFO - PROCESS | 24511 | 15:46:57 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47437/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:46:57 INFO - PROCESS | 24511 | RSS: Main: 195719168 15:46:57 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | Cycle 1(1): loaded http://localhost:47437/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47437/tests/kraken/driver.html?testName=ai-astar) 15:46:59 INFO - PROCESS | 24511 | RSS: Main: 245895168 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | __start_tp_report 15:46:59 INFO - PROCESS | 24511 | _x_x_mozilla_page_load 15:46:59 INFO - PROCESS | 24511 | _x_x_mozilla_page_load_details 15:46:59 INFO - PROCESS | 24511 | |i|pagename|runs| 15:46:59 INFO - PROCESS | 24511 | |0;ai-astar;102;111;111;112;114;112;114;111;112;114 15:46:59 INFO - PROCESS | 24511 | |1;audio-beat-detection;137;115;116;156;121;120;115;114;136;115 15:46:59 INFO - PROCESS | 24511 | |2;audio-dft;160;161;161;160;160;162;160;161;161;159 15:46:59 INFO - PROCESS | 24511 | |3;audio-fft;76;75;76;114;75;76;77;76;104;75 15:46:59 INFO - PROCESS | 24511 | |4;audio-oscillator;82;82;109;82;83;82;96;82;82;99 15:46:59 INFO - PROCESS | 24511 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;113 15:46:59 INFO - PROCESS | 24511 | |6;imaging-darkroom;147;145;146;146;147;145;147;146;146;146 15:46:59 INFO - PROCESS | 24511 | |7;imaging-desaturate;94;94;94;94;93;94;93;94;93;94 15:46:59 INFO - PROCESS | 24511 | |8;json-parse-financial;55;54;55;55;55;60;57;57;54;55 15:46:59 INFO - PROCESS | 24511 | |9;json-stringify-tinderbox;54;55;53;54;53;54;56;53;54;54 15:46:59 INFO - PROCESS | 24511 | |10;stanford-crypto-aes;84;76;83;79;75;75;79;76;84;83 15:46:59 INFO - PROCESS | 24511 | |11;stanford-crypto-ccm;141;121;121;121;121;119;125;119;156;134 15:46:59 INFO - PROCESS | 24511 | |12;stanford-crypto-pbkdf2;160;165;162;167;157;171;160;161;156;163 15:46:59 INFO - PROCESS | 24511 | |13;stanford-crypto-sha256-iterative;63;60;60;60;64;60;68;58;59;59 15:46:59 INFO - PROCESS | 24511 | __end_tp_report 15:46:59 INFO - PROCESS | 24511 | __start_cc_report 15:46:59 INFO - PROCESS | 24511 | _x_x_mozilla_cycle_collect,1650 15:46:59 INFO - PROCESS | 24511 | __end_cc_report 15:46:59 INFO - PROCESS | 24511 | __startTimestamp1491432419767__endTimestamp 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | ------- Summary: start ------- 15:46:59 INFO - PROCESS | 24511 | Number of tests: 14 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:3.50 (3.1%) stddev-sans-first:1.32 15:46:59 INFO - PROCESS | 24511 | Values: 102.0 111.0 111.0 112.0 114.0 112.0 114.0 111.0 112.0 114.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:120.50 stddev:14.01 (11.6%) stddev-sans-first:14.11 15:46:59 INFO - PROCESS | 24511 | Values: 137.0 115.0 116.0 156.0 121.0 120.0 115.0 114.0 136.0 115.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 15:46:59 INFO - PROCESS | 24511 | Values: 160.0 161.0 161.0 160.0 160.0 162.0 160.0 161.0 161.0 159.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:14.23 (18.7%) stddev-sans-first:14.90 15:46:59 INFO - PROCESS | 24511 | Values: 76.0 75.0 76.0 114.0 75.0 76.0 77.0 76.0 104.0 75.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:82.00 stddev:9.81 (12.0%) stddev-sans-first:10.17 15:46:59 INFO - PROCESS | 24511 | Values: 82.0 82.0 109.0 82.0 83.0 82.0 96.0 82.0 82.0 99.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 15:46:59 INFO - PROCESS | 24511 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 15:46:59 INFO - PROCESS | 24511 | Values: 147.0 145.0 146.0 146.0 147.0 145.0 147.0 146.0 146.0 146.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:46:59 INFO - PROCESS | 24511 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 93.0 94.0 93.0 94.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:55.00 stddev:1.83 (3.3%) stddev-sans-first:1.92 15:46:59 INFO - PROCESS | 24511 | Values: 55.0 54.0 55.0 55.0 55.0 60.0 57.0 57.0 54.0 55.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#9] json-stringify-tinderbox Cycles:10 Average:54.00 Median:54.00 stddev:0.94 (1.7%) stddev-sans-first:1.00 15:46:59 INFO - PROCESS | 24511 | Values: 54.0 55.0 53.0 54.0 53.0 54.0 56.0 53.0 54.0 54.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:81.00 stddev:3.81 (4.7%) stddev-sans-first:3.66 15:46:59 INFO - PROCESS | 24511 | Values: 84.0 76.0 83.0 79.0 75.0 75.0 79.0 76.0 84.0 83.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:123.00 stddev:12.27 (10.0%) stddev-sans-first:12.05 15:46:59 INFO - PROCESS | 24511 | Values: 141.0 121.0 121.0 121.0 121.0 119.0 125.0 119.0 156.0 134.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:162.50 stddev:4.54 (2.8%) stddev-sans-first:4.75 15:46:59 INFO - PROCESS | 24511 | Values: 160.0 165.0 162.0 167.0 157.0 171.0 160.0 161.0 156.0 163.0 15:46:59 INFO - PROCESS | 24511 | 15:46:59 INFO - PROCESS | 24511 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:60.00 stddev:3.03 (5.1%) stddev-sans-first:3.14 15:46:59 INFO - PROCESS | 24511 | Values: 63.0 60.0 60.0 60.0 64.0 60.0 68.0 58.0 59.0 59.0 15:46:59 INFO - PROCESS | 24511 | -------- Summary: end -------- 15:46:59 INFO - PROCESS | 24511 | 15:47:00 INFO - TEST-INFO | 24511: exit 0 15:47:00 INFO - TEST-OK | kraken | took 60407ms 15:47:00 INFO - SUITE-END | took 1175s 15:47:00 INFO - Completed test suite (00:19:35) 15:47:00 INFO - javascript benchmark 15:47:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7909.972142767623, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.16585270756, 3419.145213696575, 3427.6915077643007, 3436.742244331676, 3463.5497385339904, 5660.094384898412, 5746.48281654651, 5748.25272714547, 5756.769729210831, 5757.309122905247, 5738.254026976487, 5759.798407055754, 5807.041838096709, 5809.419058094199, 5809.9709501452435, 5125.820596279113, 5133.640645154831, 5135.0, 5146.3309769729885, 5174.301469301652, 5074.162763144077, 5204.739763011853, 5224.242484839696, 5225.190095535198, 5250.212468129792, 5119.206522988943, 5128.461511541291, 5176.50823171798, 5240.266362709223, 5258.684478931257, 5224.555912747421, 5238.0, 5254.605904557155, 5255.1854462557985, 5259.894802103937, 5126.384833819965, 5170.844874653766, 5212.635115541913, 5215.426303106655, 5235.371755389339, 5568.053430916754, 5596.860078498029, 5605.439456054402, 5640.8307750767135, 5646.830595082154, 6134.4172303631085, 6137.634838194141, 6140.631562106243, 6141.969290153521, 6146.016637338049, 6149.422992452974, 6186.257649082094, 6189.04069869171, 6190.535709821781, 6191.442770150708, 5651.434856514356, 5682.0, 5786.71066446676, 5795.710214489259, 5831.620944638585, 6395.296517383084, 6398.968005159944, 6405.167328247391, 6405.615663060231, 6408.166938297992, 6063.211782468251, 6169.753209871645, 6175.660338681416, 6186.876262474815, 6191.938080619227, 5940.851478713067, 5960.433758792908, 6105.8168254952425, 6236.939720247568, 6270.655113968684, 6176.320604733476, 6177.938220617736, 6180.969095154495, 6185.969070154621, 6202.038684003896, 5997.0, 6048.425399587032, 6051.183090282843, 6178.196834411498, 6190.721417536131, 4690.437147542249, 4711.2226482629985, 4720.811167553259, 4731.597814185764, 4732.763361831963, 4118.629323360912, 4235.597618226264, 4239.342901850218, 4241.27898257297, 4282.421873047098, 5982.760689572456, 6007.669578173155, 6044.546659000504, 6115.240341084353, 6118.174046503754, 6236.875262494725, 6247.96876015617, 6253.5622506425025, 6256.217972753406, 6261.530385221126, 6071.180390647294, 6080.0575910733905, 6080.787172448943, 6086.178365920633, 6091.543134264948, 5131.179011358165, 5176.430592634807, 5210.635255532152, 5260.132078207053, 5311.468853114735, 6529.2817790043055, 6531.510136739858, 6534.0, 6537.378948999933, 6539.705713242915, 6191.102290167991, 6194.4115309045565, 6224.470919971672, 6246.281677607133, 6251.874962500816, 6229.937700622936, 6272.843178920563, 6397.424231819158, 6462.289148193601, 6528.347165283433, 6174.351693072343, 6178.783742568989, 6186.0, 6186.1648677427975, 6187.071939209155, 6272.8118156455375, 6274.843128921813, 6278.560500764903, 6279.058141278845, 6282.874342513215, 5815.9709201453725, 5931.7627294907725, 6025.367336429612, 6087.147799308012, 6089.177960975212, 6097.603655762361, 6107.083937409424, 6122.093225952662, 6123.130109042633, 6123.313124872248, 6084.361142080018, 6087.208662873798, 6109.969450152721, 6112.358202388685, 6125.540584456095, 5928.199693041384, 5942.168096466585, 6068.059450785136, 6071.5446341523675, 6130.877382452415, 5951.940480595139, 5954.07711804671, 5955.196048533566, 5960.821175364746, 5960.910586341295], "value": 5706.884407967032, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.1941941541966, 817.6892780743317, 837.3594200436663, 896.5472436419611, 910.6493999810072, 32272.838635806816, 32582.0, 44568.88577785553, 45399.546004539945, 45406.318905216365, 46436.76781616096, 46591.76704116483, 46617.0, 46700.766496167475, 46720.29919551201, 7750.689972401097, 9297.395669281508, 9408.388454750453, 9423.48170850603, 9432.858507122399, 7787.415943804237, 10829.620963266287, 11000.889991100106, 11013.724656883602, 11026.338419694805, 11106.111511079094, 11118.0, 11130.666080017612, 11133.053690436323, 11156.10751139911, 10979.231453798197, 10984.780304393948, 11013.0, 11014.614488492944, 11031.117510599133, 10755.731106722316, 10812.72968175794, 10832.241743077988, 10840.078593319577, 10865.51105200268, 43294.13411731779, 43320.13359732819, 43377.56622433767, 43438.565614343766, 43511.34732979008, 4181.498220213563, 4187.72779769314, 4228.513720922103, 4308.892277693051, 4336.3712261722185, 29208.41583168346, 29646.258843529085, 29697.55453668175, 30114.0, 30960.380792384025, 4246.766427846467, 4325.156594464081, 4339.088791353819, 4343.9348409773875, 4429.822807087713, 236960.0, 237859.0, 238097.0, 238492.0, 238790.99999999825, 55100.448995510334, 55113.44886551164, 55128.448715513136, 55185.172222416695, 55292.170617440774, 57923.71038144867, 58057.419425806045, 58096.709516452145, 58216.41783582194, 58285.125723114186, 16189.0, 16635.66728665444, 16647.16764161787, 16705.248263827914, 16718.164091795363, 13028.283444410648, 13216.867831321755, 14881.404743810126, 14882.702345953021, 14887.18120503361, 13256.138351007347, 13551.25468099264, 13778.379972901244, 13810.0, 13962.790558141636, 5400.729963501888, 5416.2688037114485, 5433.843110020837, 5603.467670571371, 5650.180723795027, 938.8169306985072, 949.7435692363023, 953.2897990996752, 955.3742298794301, 972.7957129002847, 27032.324191894968, 28628.14115576536, 28845.134645960654, 29788.40423191524, 31026.379472410426, 1133.8072527670315, 1137.1528211482498, 1154.7921374152734, 1155.526234243956, 1173.372245848467, 15207.467738629095, 15213.923930380499, 15238.542843714706, 15376.923115384352, 15457.227138643248, 326267.0, 329633.0, 347531.0, 438404.0, 441252.0, 52887.73556132195, 53039.20441193385, 53158.0, 53177.202341964905, 53193.2021019685, 1490.6944076464515, 1501.8648321651103, 1512.8108986376483, 1515.727169109571, 1519.270750039987, 27204.0, 28593.428131436838, 28624.7081585612, 28973.855130724212, 29064.418711626073, 38056.80971595124, 38367.61632383752, 38442.0, 38454.80772596119, 38515.0, 308314.0, 310246.0, 310773.0, 311070.0, 311874.0, 24675.8766206175, 25550.105746299534, 28371.574426384035, 29268.512176464388, 29719.405611888073, 38882.22235555216, 38914.0, 38932.41601375925, 39170.2165956685, 39369.212615746954, 30034.0, 30274.54588181135, 30392.848035759678, 30529.694703052686, 30776.69223307738, 29743.851280743456, 29960.550591740706, 30182.698173018867, 30344.241393965327, 30581.23546911345, 34355.312893742484, 34632.65367346294, 34936.47595286022, 35025.0, 36549.0, 30464.695353046187, 30760.230994225323, 30841.383172336875, 30896.0, 31000.379992400478, 24293.75706242915, 25209.495810084063, 25519.36201594975, 25523.36191595225, 25725.1620833914], "value": 18872.993932787125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.4086528378392, 461.6814398065335, 475.54347826086956, 478.50952773407266, 484.9684770489918, 19922.7011594826, 21261.25585604504, 21506.677399838976, 21827.36294777892, 21997.780022199775, 22276.0, 22447.775522244814, 22609.0, 22739.772602273973, 22912.198073067448, 6775.0, 7060.364567188952, 7457.254274572527, 7701.6919323227, 7745.109312429058, 7564.697412103509, 7717.807054823618, 7783.727569535067, 7787.415943804209, 7787.649555770004, 7814.960925195366, 7817.0619525657, 7822.335101516378, 7826.608669566499, 7833.804154896116, 7591.924080759177, 7817.101033381177, 7873.921260787376, 7884.881726774103, 7886.171951945049, 7417.0, 7448.776536703854, 7504.737334193277, 7507.249275072503, 7649.235076492362, 25419.61870571943, 25721.228363149134, 26052.34869128268, 26127.869360653265, 26247.475050499073, 2937.251000994754, 3031.227037105546, 3070.1250143708935, 3131.827749473778, 3157.700018498239, 15711.764323535157, 16502.25739841698, 16667.749983750255, 16752.66494670112, 16958.830411695973, 2278.2481781012225, 2345.155743932183, 2362.9527409451716, 2403.2429784617966, 2408.5182963407387, 123843.0, 126453.36773316166, 127293.36353318267, 127316.36341818325, 128361.0, 17871.73192402115, 18429.723554146698, 18687.626247474975, 18909.52726181829, 19214.903925480285, 33437.0, 33613.66386336106, 33668.32663346719, 33796.66203337984, 33877.153071173416, 10399.48002599867, 10416.479176041168, 10535.789284214427, 10670.62652807148, 10775.137988960865, 8720.8691869622, 8887.711281864093, 8899.688510902088, 9048.276137908952, 9084.364094513452, 8934.329925255632, 9349.859752103725, 9567.952160239154, 9691.27315451344, 9772.95113524442, 3514.38498262803, 3621.8732344367822, 3758.4778164843283, 3760.7555508891833, 3820.541535015816, 965.8502932045403, 972.3096601412934, 975.1662328708993, 984.9113579777854, 989.618996686281, 20700.275490357766, 21676.34970950874, 21679.0, 22457.55084898293, 22744.51321054862, 1082.1288862465633, 1085.316250762015, 1097.939613321275, 1113.8886111388958, 1130.496928866663, 11378.089752819755, 11583.247088939192, 11654.650360489199, 11689.473973671205, 12414.131010829155, 682102.0, 689441.4880389974, 695860.0, 695887.0, 702637.0, 118119.0, 122328.0, 122601.0, 124957.0, 125182.37408812897, 1329.8257349234073, 1338.4110991164052, 1358.4226703651107, 1370.6573356660836, 1373.986260137406, 18883.236614801754, 19716.50708732293, 19760.40718778439, 19846.801531984496, 19858.304959326353, 52987.0, 53570.0, 53828.730856345464, 54149.45850541444, 54807.451925480236, 71769.64115179391, 72263.47341078163, 72376.63811680907, 73845.6307718458, 75032.2496775047, 10709.607750992322, 12102.818457722966, 12112.697182570506, 12280.078994075659, 12956.481740730642, 12673.873261267268, 13797.655058623213, 14013.789793153315, 14417.207053612152, 14573.562793116223, 11508.36703981289, 11534.538618455168, 11674.883251167379, 11828.467718952495, 11951.342676152548, 11193.328400296008, 11299.887001129884, 11306.4912078955, 11340.886591133984, 11367.715807104558, 11899.405029748305, 12354.0, 12472.376381181086, 12499.812502812647, 12593.244405335709, 11905.52377904874, 11908.285502869856, 12063.457144428698, 12089.57686480969, 12148.817767733315, 11856.585019524275, 11940.402979851146, 12775.23348599087, 12868.356582171042, 12957.676058098623], "value": 11874.565996249548, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.6753514226866, 1536.5774412036687, 1573.1819453883982, 1583.3824808324753, 1592.0686398456903, 9702.805943881118, 11147.554097836088, 11489.59786407476, 11768.705782355446, 11878.168528203028, 11463.713407164825, 11555.19113662044, 11565.942170289158, 11583.0, 11588.478518466669, 2078.968815467769, 2145.95708085839, 2470.160145550508, 2516.1319344826006, 2518.320053585531, 2483.552960467115, 2545.1346542175656, 2559.462512872299, 2575.304667739709, 2576.1498705427166, 2423.612222044473, 2459.5449841779327, 2479.2438306316567, 2498.7001559812848, 2509.8619575923317, 2438.5854404751235, 2440.0849681369486, 2460.6186041163646, 2462.8768561571937, 2472.245964980689, 2372.181597348912, 2373.133806160756, 2389.581823180954, 2392.5454163709014, 2397.460571371445, 11799.997000254989, 11843.644690659294, 11943.522259109712, 11944.64166075019, 11956.163068585203, 5846.298444186682, 5985.610935289193, 5985.700714964234, 5991.041433370639, 6003.7898673546215, 3498.265364273506, 3532.346515894568, 3581.713462922986, 3594.3170797548382, 3609.0796846803896, 1293.172369683394, 1319.0634649398937, 1325.7083441642824, 1327.1771501669027, 1410.654389674526, 16745.413910513194, 17935.73096403555, 17957.551061223443, 17985.640287194314, 18004.54988625282, 4843.806247750051, 4937.925931111036, 4997.650164488451, 5052.646314758005, 5059.114654935372, 13168.73662526744, 13289.601311960656, 13313.866861331457, 13318.267495287851, 13326.800097998537, 6135.785247516315, 6141.048137538689, 6145.539084568675, 6145.539084568675, 6150.169727086786, 4337.978310108492, 5172.482751724798, 5446.8910621787345, 5671.886562268732, 5678.659280443187, 4037.555868854424, 4112.424260603518, 4176.2691528982305, 4185.267578173807, 4211.241976444208, 1065.3980501016902, 1071.8767341755643, 1082.8050950828926, 1087.2823936202226, 1097.8353247012851, 1070.3043022035738, 1093.5899037860802, 1098.2147764348529, 1102.9007389334997, 1107.9113670906308, 7435.962820185864, 7527.661255243527, 7548.207438218908, 7675.53946763196, 7738.303552680286, 984.7242772023844, 1015.355249416611, 1043.3218408034686, 1043.3635482355758, 1048.6801525534786, 4543.227651299221, 4638.373819534387, 4640.280756482751, 4812.51874812516, 4962.404511458576, 14604.269786510631, 15282.770758438632, 15293.082415055133, 15317.846821531642, 15321.310541025681, 14222.644433889029, 14762.40950361995, 14770.852291477162, 14787.852121478863, 15120.243987800566, 10840.241183117107, 11037.448127593589, 11049.889501105046, 11054.778904421866, 11075.446227688582, 7472.065991751031, 7597.274565103319, 7850.842983140419, 7890.092639346549, 7928.682852686059, 13163.47346106145, 13355.465781368637, 13365.198088114745, 13379.0, 13443.058985871286, 13568.79646805279, 13684.178949263076, 13708.24604646754, 13720.245386503439, 13869.861301386856, 8687.174718401742, 8933.865992010255, 9643.469609171563, 9980.950095249478, 10190.694279171637, 11212.55149793999, 11269.887301127212, 11333.093352531943, 11344.262622929484, 11370.374629395463, 8875.866861996947, 8879.467231966102, 8973.461592304482, 9206.539673016456, 9213.447193168431, 9067.954660226656, 9265.027172146827, 9306.255499559884, 9309.069093090637, 9473.100055494717, 8625.0, 8732.0, 8800.823983520166, 8805.515696636747, 8825.646974120964, 6548.214214294315, 6585.0, 6627.1716035495565, 6639.3692599203, 6704.966475167788, 6558.606483610999, 6578.76974305897, 6618.536702430783, 6626.6686665667485, 6783.18601767791], "value": 5511.434500369801, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.55525920154236, 426.3519450435338, 428.61210604403027, 434.1534008683067, 436.8471035137702, 5156.200788877721, 5156.278121063054, 5221.765020574074, 5262.500062494062, 5265.946810637869, 5081.6696914700515, 5111.182210846266, 5119.488051194888, 5132.615053870956, 5133.871653208672, 4188.2251783420015, 4276.978615106936, 4287.635550978171, 4288.421063156473, 4295.140971805636, 4297.269464191079, 4347.586979236968, 4369.4756629204385, 4379.671524635649, 4379.912401751963, 4340.631046361063, 4353.28170851809, 4384.868453946371, 4389.407429996957, 4403.295472724365, 4109.97945010276, 4260.997331254305, 4276.593723596254, 4377.540358262384, 4400.229959757062, 3987.0, 4006.696149713897, 4022.839086436539, 4046.5751096134777, 4051.453053837753, 4977.676451030671, 5006.524380183877, 5018.569707633344, 5019.949800501985, 5020.623453241022, 4915.754212289407, 4933.432655244621, 5011.974940125276, 5028.555439523938, 5034.697918124888, 5331.866703332409, 5339.67961922282, 5353.705546194958, 5357.249985002103, 5364.566677326514, 4521.231390663595, 4521.711312275986, 4537.185125949613, 4594.264917613199, 4628.847585872585, 5390.735890925821, 5597.328320601513, 5599.880023995217, 5651.547876169897, 5757.309122905236, 4624.65315101362, 4629.782301300631, 4632.397788287569, 4642.419697537808, 4653.255479123324, 4703.929441058387, 4712.811487540528, 4713.552212539803, 4732.0, 4732.479427262998, 4905.48492408292, 5009.273655319958, 5039.571636410873, 5044.899102017939, 5087.669301495391, 5353.491418315331, 5362.51737343641, 5373.919391209135, 5388.272583201296, 5411.079066931262, 1616.7413213885723, 1624.7400415933394, 1628.8696904247868, 1637.0914142650704, 1640.3028712797013, 1184.3190165654783, 1255.0712472770085, 1258.9559365422167, 1268.213707501355, 1281.39774306076, 5274.894502109936, 5299.735013249399, 5315.627906046617, 5316.08620896477, 5340.893182136336, 5461.968607422839, 5469.562435005269, 5491.533219676292, 5493.395726470085, 5514.972425137848, 4877.000214955997, 5314.787408503694, 5321.3348331458565, 5322.168039011905, 5329.333833270841, 2905.709429057077, 2914.591957126004, 2914.810537315068, 2930.3113768264443, 2940.8529573521237, 5435.809746658848, 5681.233033540502, 5755.942440575541, 5781.913271300934, 5806.21616081832, 5381.838544843661, 5604.663720176803, 5605.80379686709, 5660.999999999917, 5679.1258884568, 5588.469095435927, 5631.802886898939, 5784.71076446176, 5806.506446951972, 5815.883682326415, 5267.868303292295, 5412.648177868426, 5419.349678038503, 5489.807856725155, 5505.58653634749, 5308.416074231909, 5349.411564727799, 5450.691833959901, 5491.478309560585, 5512.862178445571, 5755.683437410982, 5837.211976383303, 5837.548229743433, 5855.765769369177, 5862.355140934408, 4495.25828238337, 4674.270519907656, 4689.296605509201, 4719.8820029498165, 4760.428748550196, 5487.835364939058, 5557.833265002057, 5570.938752661541, 5603.719814009365, 5609.915851262316, 5418.45815418455, 5422.5119739223655, 5465.0, 5473.042217611823, 5481.835544933659, 5285.339332583427, 5406.675599463887, 5407.513323800877, 5458.6724796512335, 5470.398256191896, 5410.48600382963, 5411.269478620336, 5420.620556561003, 5435.918461223164, 5453.145930383688, 5300.231466437422, 5425.348958125051, 5441.775600489858, 5463.399026107046, 5516.3380394352935, 5350.892982140257, 5351.127105513039, 5366.436524164907, 5391.81128660495, 5405.702686352289], "value": 4366.6697516588765, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [849.3035710717206, 860.8837806896067, 898.3935843305767, 914.9999999999999, 928.1600151862558, 15900.20498975051, 16795.412160574368, 17522.561935951606, 18090.185941632626, 18361.7245741314, 17680.734788978174, 17913.552161195945, 18844.152013159408, 18960.336388226417, 19028.61942761144, 872.7163671806657, 873.5981448533666, 883.5184824270788, 890.1943740914483, 890.9643614255455, 866.0040952904179, 875.5753459572103, 876.8903887014123, 878.2885862451403, 879.0286733159862, 930.7719608695879, 932.9253659707207, 933.9392939458913, 934.1312579301247, 937.1003836317144, 930.33481061041, 931.8881734191906, 932.4498545857977, 932.7388331267222, 933.1881263300904, 687.6355531568277, 687.845234822161, 688.0092666560188, 688.0985908459884, 688.3048121397375, 16661.750073748903, 16771.2452939618, 17074.829251707448, 18074.186661600128, 18236.45290641283, 16138.677226455406, 16318.112815596056, 16423.83576164235, 16444.0, 16706.99758014523, 13272.668183295398, 13440.798388024188, 13452.461901524026, 13596.456141754317, 13597.592072237849, 3878.592747761501, 3939.2909276330247, 3951.1900060487533, 3964.1477082427127, 4031.677465802729, 85565.0, 85678.14321856826, 85875.57062214712, 88192.0, 89344.0, 12374.257544547356, 12459.190152640049, 12524.937375313248, 12562.497500100077, 12579.182353147016, 21760.45598860041, 21794.782052179275, 21798.564028719655, 21803.236886708888, 21944.0, 8319.958400208081, 8393.496390216607, 8404.705835295736, 8585.570721463902, 8629.568521573896, 4729.314249433813, 4881.072596206709, 4884.3650325457465, 4972.303877457159, 4985.950140498621, 3682.7237957152784, 3786.4888240087785, 3791.014336272534, 3826.9617303827163, 3838.1556057667267, 660.1484085529613, 660.2802944790202, 661.3386613386614, 665.1851481934688, 685.6263336481629, 828.5955305756685, 832.6710949175163, 847.4195176304252, 870.8127752533236, 882.6292956958093, 12215.389230538438, 12478.313692746986, 12486.250824950532, 12777.361131943366, 12842.0, 675.4393853102011, 693.9548929319578, 704.2183176673888, 721.4444877444339, 866.5190819095336, 5285.31290932184, 5626.767106590828, 5691.288588926384, 5703.0, 5718.1136923776885, 78846.0, 81143.0, 81235.0, 82067.0, 83461.58269208737, 56722.71638641781, 68046.0, 69692.30307696859, 70472.64763676148, 70709.64645176742, 59830.40169598248, 59982.40017599768, 60821.695891520256, 61545.692271538355, 61767.38232617706, 11822.29066255993, 12412.379381031093, 12491.812622810483, 12497.750044998867, 12575.384079081769, 41650.0, 41706.58293417027, 42498.575014249465, 42807.57192428036, 43347.783261083496, 52761.7361913188, 53335.733321334694, 55805.0, 56541.71729141328, 59466.0, 12630.179038362478, 12682.619521414372, 13021.934890325489, 13182.472701092232, 13290.269035203157, 16336.019838809709, 16942.57643558921, 17117.22972466217, 17118.74321885148, 17968.01175935336, 14978.0, 15480.225988700295, 16071.517854464384, 16183.0, 16611.41860034893, 14646.414143434144, 14950.925245373703, 15038.849611504183, 15513.844861551692, 15625.68748625044, 15577.766333505233, 15919.761203582188, 15925.362985480451, 15976.840231598, 16643.3342666292, 11423.200375973602, 11511.30932144074, 11569.19015668895, 11844.644660660195, 12103.878961210276, 12264.202826816228, 12560.874391255971, 12620.242785432903, 12719.427625756678, 12863.0], "value": 7956.792266786543, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 112.0, 114.0, 112.0, 114.0, 111.0, 112.0, 114.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 115.0, 116.0, 156.0, 121.0, 120.0, 115.0, 114.0, 136.0, 115.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 160.0, 162.0, 160.0, 161.0, 161.0, 159.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 114.0, 75.0, 76.0, 77.0, 76.0, 104.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 109.0, 82.0, 83.0, 82.0, 96.0, 82.0, 82.0, 99.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 146.0, 146.0, 147.0, 145.0, 147.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 55.0, 55.0, 60.0, 57.0, 57.0, 54.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 53.0, 54.0, 53.0, 54.0, 56.0, 53.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 76.0, 83.0, 79.0, 75.0, 75.0, 79.0, 76.0, 84.0, 83.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 121.0, 121.0, 121.0, 121.0, 119.0, 125.0, 119.0, 156.0, 134.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 162.0, 167.0, 157.0, 171.0, 160.0, 161.0, 156.0, 163.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 64.0, 60.0, 68.0, 58.0, 59.0, 59.0], "value": 61.1, "unit": "score"}]}]} 15:47:01 INFO - Return code: 0 15:47:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:47:01 ERROR - # TBPL SUCCESS # 15:47:01 INFO - Running post-action listener: _resource_record_post_action 15:47:01 INFO - [mozharness: 2017-04-05 22:47:01.085635Z] Finished run-tests step (success) 15:47:01 INFO - Running post-run listener: _resource_record_post_run 15:47:01 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 266240; Write bytes: 369668096; Read time: 28; Write time: 343664 15:47:01 INFO - TinderboxPrint: CPU usage
13.1% 15:47:01 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 15:47:01 INFO - TinderboxPrint: I/O write bytes / time
369,668,096 / 343,664 15:47:01 INFO - TinderboxPrint: CPU idle
8,269.1 (86.8%) 15:47:01 INFO - TinderboxPrint: CPU user
1,203.2 (12.6%) 15:47:01 INFO - TinderboxPrint: Swap in / out
0 / 0 15:47:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115990528; Read time: 0; Write time: 138124 15:47:01 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 252903424; Read time: 28; Write time: 205516 15:47:01 INFO - Running post-run listener: _upload_blobber_files 15:47:01 INFO - Blob upload gear active. 15:47:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:47:01 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 15:47:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:47:02 INFO - (blobuploader) - INFO - Open directory for files ... 15:47:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:47:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:47:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:05 INFO - (blobuploader) - INFO - Done attempting. 15:47:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:47:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:47:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:06 INFO - (blobuploader) - INFO - Done attempting. 15:47:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:47:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:47:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:07 INFO - (blobuploader) - INFO - Done attempting. 15:47:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:47:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:47:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:07 INFO - (blobuploader) - INFO - Done attempting. 15:47:07 INFO - (blobuploader) - INFO - Iteration through files over. 15:47:07 INFO - Return code: 0 15:47:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:47:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:47:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2ca8b0f87e2cc56a2ded99444057b459661de333c61457a53086b259026ca80e8608a35cf7cf9101d4590f6bbde2ffba45d66d4f35d656322a9e9754f149a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/350e221420b5d6d68a21cd7512eabd86cb231778ecc84f1d0f9ed1c41bce467ac13608736f71ecbd42875b033020e4b486ff6356ea0baf945f05d6e8ee29d74f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f30c083d0c7be8f81e61a961fa8d036d739034bf505c7548c2f24d39a57112c4b1767210f072555f0766a06464ad59b54d1f025bd29d63ae694065ca2d17ac6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64dfac5bb2ba4f3be22bccf82e51f9e9237ee603f77047ac1ede319abf5e2edfe86df963fc2a4eaacf4f08dbd0daa0f4286b42281ae1ed7096fe2560c236f6ab"} 15:47:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:47:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:47:07 INFO - Contents: 15:47:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2ca8b0f87e2cc56a2ded99444057b459661de333c61457a53086b259026ca80e8608a35cf7cf9101d4590f6bbde2ffba45d66d4f35d656322a9e9754f149a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/350e221420b5d6d68a21cd7512eabd86cb231778ecc84f1d0f9ed1c41bce467ac13608736f71ecbd42875b033020e4b486ff6356ea0baf945f05d6e8ee29d74f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f30c083d0c7be8f81e61a961fa8d036d739034bf505c7548c2f24d39a57112c4b1767210f072555f0766a06464ad59b54d1f025bd29d63ae694065ca2d17ac6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64dfac5bb2ba4f3be22bccf82e51f9e9237ee603f77047ac1ede319abf5e2edfe86df963fc2a4eaacf4f08dbd0daa0f4286b42281ae1ed7096fe2560c236f6ab"} 15:47:07 INFO - Running post-run listener: copy_logs_to_upload_dir 15:47:07 INFO - Copying logs to upload dir... 15:47:07 INFO - mkdir: /builds/slave/test/build/upload/logs 15:47:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.689660 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-05 15:47:07.695160) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 15:47:07.700128) ========= 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=3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2ca8b0f87e2cc56a2ded99444057b459661de333c61457a53086b259026ca80e8608a35cf7cf9101d4590f6bbde2ffba45d66d4f35d656322a9e9754f149a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/350e221420b5d6d68a21cd7512eabd86cb231778ecc84f1d0f9ed1c41bce467ac13608736f71ecbd42875b033020e4b486ff6356ea0baf945f05d6e8ee29d74f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f30c083d0c7be8f81e61a961fa8d036d739034bf505c7548c2f24d39a57112c4b1767210f072555f0766a06464ad59b54d1f025bd29d63ae694065ca2d17ac6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64dfac5bb2ba4f3be22bccf82e51f9e9237ee603f77047ac1ede319abf5e2edfe86df963fc2a4eaacf4f08dbd0daa0f4286b42281ae1ed7096fe2560c236f6ab"} build_url:https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011440 build_url: 'https://queue.taskcluster.net/v1/task/EUr_yszeQHelf3G5I6a8WQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2ca8b0f87e2cc56a2ded99444057b459661de333c61457a53086b259026ca80e8608a35cf7cf9101d4590f6bbde2ffba45d66d4f35d656322a9e9754f149a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/350e221420b5d6d68a21cd7512eabd86cb231778ecc84f1d0f9ed1c41bce467ac13608736f71ecbd42875b033020e4b486ff6356ea0baf945f05d6e8ee29d74f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f30c083d0c7be8f81e61a961fa8d036d739034bf505c7548c2f24d39a57112c4b1767210f072555f0766a06464ad59b54d1f025bd29d63ae694065ca2d17ac6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64dfac5bb2ba4f3be22bccf82e51f9e9237ee603f77047ac1ede319abf5e2edfe86df963fc2a4eaacf4f08dbd0daa0f4286b42281ae1ed7096fe2560c236f6ab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 15:47:07.726635) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:47:07.726972) ========= 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=3836458cccd887f2d2de3cf1000001de-1491431175.444412-569817387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 15:47:07.811556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 15:47:07.811922) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 15:47:07.812213) ========= ========= Total master_lag: 0.18 =========