builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-074 starttime: 1491480314.96 results: success (0) revision: 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:14.964927) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:14.965364) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:14.965680) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:14.982660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:14.982991) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-06 05:05:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 54.9M=0s 2017-04-06 05:05:15 (54.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149768 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:15.149070) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:15.149465) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014848 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:15.202157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 05:05:15.202979) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a --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 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a --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=23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-06 05:05:15,321 truncating revision to first 12 chars 2017-04-06 05:05:15,321 Setting DEBUG logging. 2017-04-06 05:05:15,321 attempt 1/10 2017-04-06 05:05:15,321 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9d164f1dc3c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 05:05:16,628 unpacking tar archive at: mozilla-aurora-9d164f1dc3c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.623580 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 05:05:16.841884) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:16.842248) ========= script_repo_revision: 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:16.842664) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:16.843028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 05:05:16.854307) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-06 05:05:16.855137) ========= /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=23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:05:17 INFO - MultiFileLogger online at 20170406 05:05:17 in /builds/slave/test 05:05: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 05:05:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:17 INFO - {'append_to_log': False, 05:05:17 INFO - 'base_work_dir': '/builds/slave/test', 05:05:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:05:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:17 INFO - 'branch': 'Mozilla-Aurora', 05:05:17 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:17 INFO - 'config_files': ('talos/linux_config.py',), 05:05:17 INFO - 'default_actions': ('clobber', 05:05:17 INFO - 'read-buildbot-config', 05:05:17 INFO - 'download-and-extract', 05:05:17 INFO - 'populate-webroot', 05:05:17 INFO - 'create-virtualenv', 05:05:17 INFO - 'install', 05:05:17 INFO - 'run-tests'), 05:05:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:17 INFO - 'download_minidump_stackwalk': True, 05:05:17 INFO - 'download_symbols': 'ondemand', 05:05:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:17 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:17 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:17 INFO - 'gecko_profile': False, 05:05:17 INFO - 'gecko_profile_interval': 0, 05:05:17 INFO - 'installer_path': 'installer.exe', 05:05:17 INFO - 'log_level': 'info', 05:05:17 INFO - 'log_name': 'talos', 05:05:17 INFO - 'log_to_console': True, 05:05:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:05:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:05:17 INFO - 'opt_config_files': (), 05:05:17 INFO - 'pip_index': False, 05:05:17 INFO - 'suite': 'dromaeojs', 05:05:17 INFO - 'system_bits': '32', 05:05:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:17 INFO - 'title': 'talos-linux64-ix-074', 05:05:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:17 INFO - 'use_talos_json': True, 05:05:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:17 INFO - 'work_dir': 'build'} 05:05:17 INFO - [mozharness: 2017-04-06 12:05:17.074945Z] Running clobber step. 05:05:17 INFO - Running pre-action listener: _resource_record_pre_action 05:05:17 INFO - Running main action method: clobber 05:05:17 INFO - rmtree: /builds/slave/test/build 05:05:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:18 INFO - Running post-action listener: _resource_record_post_action 05:05:18 INFO - [mozharness: 2017-04-06 12:05:18.002150Z] Finished clobber step (success) 05:05:18 INFO - [mozharness: 2017-04-06 12:05:18.002257Z] Running read-buildbot-config step. 05:05:18 INFO - Running pre-action listener: _resource_record_pre_action 05:05:18 INFO - Running main action method: read_buildbot_config 05:05:18 INFO - Using buildbot properties: 05:05:18 INFO - { 05:05:18 INFO - "project": "", 05:05:18 INFO - "product": "firefox", 05:05:18 INFO - "who": "ryanvm@gmail.com", 05:05:18 INFO - "installer_path": "public/build/target.tar.bz2", 05:05:18 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:05:18 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 05:05:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:05:18 INFO - "stage_platform": "linux64-pgo", 05:05:18 INFO - "basedir": "/builds/slave/test", 05:05:18 INFO - "buildnumber": 1140, 05:05:18 INFO - "platform": "ubuntu64_hw", 05:05:18 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:05:18 INFO - "slavebuilddir": "test", 05:05:18 INFO - "taskId": "NPWtepLvRDaSyh6oSfor7w", 05:05:18 INFO - "branch": "mozilla-aurora", 05:05:18 INFO - "script_repo_revision": "production", 05:05:18 INFO - "revision": "9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a", 05:05:18 INFO - "slavename": "talos-linux64-ix-074", 05:05:18 INFO - "repo_path": "releases/mozilla-aurora" 05:05:18 INFO - } 05:05:18 INFO - Finding installer, test and symbols from parent task. 05:05:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NPWtepLvRDaSyh6oSfor7w'}, attempt #1 05:05:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NPWtepLvRDaSyh6oSfor7w'}, attempt #1 05:05:18 INFO - Task dependencies: SqgPja7oSxWWkzxwhvJofA 05:05:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA'}, attempt #1 05:05:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.tar.bz2 05:05:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/test_packages.json 05:05:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.crashreporter-symbols.zip 05:05:19 INFO - Running post-action listener: _resource_record_post_action 05:05:19 INFO - [mozharness: 2017-04-06 12:05:19.394792Z] Finished read-buildbot-config step (success) 05:05:19 INFO - [mozharness: 2017-04-06 12:05:19.395008Z] Running download-and-extract step. 05:05:19 INFO - Running pre-action listener: _resource_record_pre_action 05:05:19 INFO - Running main action method: download_and_extract 05:05:19 INFO - mkdir: /builds/slave/test/build/tests 05:05: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')]} 05:05:19 INFO - trying https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.test_packages.json 05:05:19 INFO - Downloading https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:05:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:05:19 INFO - Downloaded 896 bytes. 05:05:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:05:19 INFO - Using the following test package requirements: 05:05:19 INFO - {u'common': [u'target.common.tests.zip'], 05:05:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:05:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:05:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:05:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:05:19 INFO - u'mozbase': [u'target.common.tests.zip'], 05:05:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:05:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:05:19 INFO - u'web-platform': [u'target.common.tests.zip', 05:05:19 INFO - u'target.web-platform.tests.zip'], 05:05:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:05:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:05:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.common.tests.zip 05:05:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:05:19 INFO - Fetch https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.common.tests.zip into memory 05:05:21 INFO - Expected file size: 59541805 05:05:21 INFO - Obtained file size: 59541805 05:05:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:05:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.common.tests.zip 05:05:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:05:30 INFO - Fetch https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.common.tests.zip into memory 05:05:32 INFO - Expected file size: 59541805 05:05:32 INFO - Obtained file size: 59541805 05:05:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.talos.tests.zip 05:05:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:05:43 INFO - Fetch https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.talos.tests.zip into memory 05:05:44 INFO - Expected file size: 14404438 05:05:44 INFO - Obtained file size: 14404438 05:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:44 INFO - trying https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.tar.bz2 05:05:44 INFO - Downloading https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:05:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:05:45 INFO - Downloaded 66690749 bytes. 05:05:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.tar.bz2 05:05:45 INFO - mkdir: /builds/slave/test/properties 05:05:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:45 INFO - Contents: 05:05:45 INFO - build_url:https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.tar.bz2 05:05:45 INFO - Running post-action listener: _resource_record_post_action 05:05:45 INFO - Running post-action listener: set_extra_try_arguments 05:05:45 INFO - [mozharness: 2017-04-06 12:05:45.778272Z] Finished download-and-extract step (success) 05:05:45 INFO - [mozharness: 2017-04-06 12:05:45.778370Z] Running populate-webroot step. 05:05:45 INFO - Running pre-action listener: _resource_record_pre_action 05:05:45 INFO - Running main action method: populate_webroot 05:05:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:45 INFO - '--tppagecycles', 05:05:45 INFO - '7'], 05:05:45 INFO - 'tests': ['tsvgm']}}, 05:05:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:05:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:05:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:05:45 INFO - 'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:05:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:05:45 INFO - 'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['damp', 'tps']}, 05:05:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:05:45 INFO - 'tests': ['damp', 'tps']}, 05:05:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['dromaeo_dom']}, 05:05:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['basic_compositor_video']}, 05:05:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:05:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['a11yr', 05:05:45 INFO - 'ts_paint', 05:05:45 INFO - 'tpaint', 05:05:45 INFO - 'sessionrestore', 05:05:45 INFO - 'sessionrestore_no_auto_restore', 05:05:45 INFO - 'tabpaint']}, 05:05:45 INFO - 'other-e10s': {'tests': ['a11yr', 05:05:45 INFO - 'ts_paint', 05:05:45 INFO - 'tpaint', 05:05:45 INFO - 'sessionrestore', 05:05:45 INFO - 'sessionrestore_no_auto_restore', 05:05:45 INFO - 'tabpaint']}, 05:05:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:05:45 INFO - 'ts_paint', 05:05:45 INFO - 'tpaint', 05:05:45 INFO - 'sessionrestore', 05:05:45 INFO - 'sessionrestore_no_auto_restore', 05:05:45 INFO - 'tabpaint']}, 05:05:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:05:45 INFO - 'ts_paint', 05:05:45 INFO - 'tpaint', 05:05:45 INFO - 'sessionrestore', 05:05:45 INFO - 'sessionrestore_no_auto_restore', 05:05:45 INFO - 'tabpaint']}, 05:05:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['a11yr', 05:05:45 INFO - 'ts_paint', 05:05:45 INFO - 'tpaint', 05:05:45 INFO - 'sessionrestore', 05:05:45 INFO - 'sessionrestore_no_auto_restore', 05:05:45 INFO - 'tabpaint']}, 05:05:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['a11yr', 05:05:45 INFO - 'ts_paint', 05:05:45 INFO - 'tpaint', 05:05:45 INFO - 'sessionrestore', 05:05:45 INFO - 'sessionrestore_no_auto_restore', 05:05:45 INFO - 'tabpaint']}, 05:05:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['tsvgx', 05:05:45 INFO - 'tsvgr_opacity', 05:05:45 INFO - 'tart', 05:05:45 INFO - 'tscrollx', 05:05:45 INFO - 'cart', 05:05:45 INFO - 'tsvg_static']}, 05:05:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:05:45 INFO - 'tsvgr_opacity', 05:05:45 INFO - 'tart', 05:05:45 INFO - 'tscrollx', 05:05:45 INFO - 'cart', 05:05:45 INFO - 'tsvg_static']}, 05:05:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:05:45 INFO - 'talos_options': ['--disable-e10s'], 05:05:45 INFO - 'tests': ['tp5o']}, 05:05:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:05:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:05:45 INFO - 'talos_options': ['--disable-e10s', 05:05:45 INFO - '--xperf_path', 05:05:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:45 INFO - 'tests': ['tp5n']}, 05:05:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:05:45 INFO - 'talos_options': ['--xperf_path', 05:05:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:45 INFO - 'tests': ['tp5n']}}, 05:05:45 INFO - 'talos.zip': {'path': '', 05:05:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:45 INFO - Running post-action listener: _resource_record_post_action 05:05:45 INFO - [mozharness: 2017-04-06 12:05:45.791448Z] Finished populate-webroot step (success) 05:05:45 INFO - [mozharness: 2017-04-06 12:05:45.791538Z] Running create-virtualenv step. 05:05:45 INFO - Running pre-action listener: _resource_record_pre_action 05:05:45 INFO - Running main action method: create_virtualenv 05:05:45 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05: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 05:05:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:45 INFO - Using real prefix '/usr' 05:05:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:46 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:48 INFO - Installing pip.................done. 05:05:48 INFO - Return code: 0 05:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:05:48 INFO - Reading from file tmpfile_stdout 05:05:48 INFO - Output received: 05:05:48 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) 05:05:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:05: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', 'pip>=1.5'] in /builds/slave/test/build 05:05: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 pip>=1.5 05:05:48 INFO - Using env: {'DISPLAY': ':0', 05:05:48 INFO - 'HOME': '/home/cltbld', 05:05:48 INFO - 'LANG': 'en_US.UTF-8', 05:05:48 INFO - 'LANGUAGE': 'en_US:en', 05:05:48 INFO - 'LOGNAME': 'cltbld', 05:05:48 INFO - 'MAIL': '/var/mail/cltbld', 05:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:48 INFO - 'MOZ_NO_REMOTE': '1', 05:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:48 INFO - 'NO_EM_RESTART': '1', 05:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:48 INFO - 'PWD': '/builds/slave/test', 05:05:48 INFO - 'SHELL': '/bin/bash', 05:05:48 INFO - 'SHLVL': '1', 05:05:48 INFO - 'TERM': 'linux', 05:05:48 INFO - 'TMOUT': '86400', 05:05:48 INFO - 'USER': 'cltbld', 05:05:48 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:48 INFO - '_': '/tools/buildbot/bin/python'} 05:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05: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 05:05:48 INFO - Cleaning up... 05:05:48 INFO - Return code: 0 05:05:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:05: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 05:05: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 05:05:48 INFO - Using env: {'DISPLAY': ':0', 05:05:48 INFO - 'HOME': '/home/cltbld', 05:05:48 INFO - 'LANG': 'en_US.UTF-8', 05:05:48 INFO - 'LANGUAGE': 'en_US:en', 05:05:48 INFO - 'LOGNAME': 'cltbld', 05:05:48 INFO - 'MAIL': '/var/mail/cltbld', 05:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:48 INFO - 'MOZ_NO_REMOTE': '1', 05:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:48 INFO - 'NO_EM_RESTART': '1', 05:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:48 INFO - 'PWD': '/builds/slave/test', 05:05:48 INFO - 'SHELL': '/bin/bash', 05:05:48 INFO - 'SHLVL': '1', 05:05:48 INFO - 'TERM': 'linux', 05:05:48 INFO - 'TMOUT': '86400', 05:05:48 INFO - 'USER': 'cltbld', 05:05:48 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:48 INFO - '_': '/tools/buildbot/bin/python'} 05:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:48 INFO - Downloading/unpacking psutil>=3.1.1 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:51 INFO - Installing collected packages: psutil 05:05:51 INFO - Running setup.py install for psutil 05:05:51 INFO - building 'psutil._psutil_linux' extension 05:05:51 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 05:05:51 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 05:05:51 INFO - building 'psutil._psutil_posix' extension 05:05:51 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 05:05:51 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 05:05:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:51 INFO - Successfully installed psutil 05:05:51 INFO - Cleaning up... 05:05:51 INFO - Return code: 0 05:05:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:05:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:05:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:05:51 INFO - Using env: {'DISPLAY': ':0', 05:05:51 INFO - 'HOME': '/home/cltbld', 05:05:51 INFO - 'LANG': 'en_US.UTF-8', 05:05:51 INFO - 'LANGUAGE': 'en_US:en', 05:05:51 INFO - 'LOGNAME': 'cltbld', 05:05:51 INFO - 'MAIL': '/var/mail/cltbld', 05:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:51 INFO - 'MOZ_NO_REMOTE': '1', 05:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:51 INFO - 'NO_EM_RESTART': '1', 05:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:51 INFO - 'PWD': '/builds/slave/test', 05:05:51 INFO - 'SHELL': '/bin/bash', 05:05:51 INFO - 'SHLVL': '1', 05:05:51 INFO - 'TERM': 'linux', 05:05:51 INFO - 'TMOUT': '86400', 05:05:51 INFO - 'USER': 'cltbld', 05:05:51 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:51 INFO - '_': '/tools/buildbot/bin/python'} 05:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:05:53 INFO - Installing collected packages: mozsystemmonitor 05:05:53 INFO - Running setup.py install for mozsystemmonitor 05:05:54 INFO - Successfully installed mozsystemmonitor 05:05:54 INFO - Cleaning up... 05:05:54 INFO - Return code: 0 05:05:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:05:54 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')]} 05:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 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')]} 05:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:05:54 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 05:05:54 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 05:05:54 INFO - Using env: {'DISPLAY': ':0', 05:05:54 INFO - 'HOME': '/home/cltbld', 05:05:54 INFO - 'LANG': 'en_US.UTF-8', 05:05:54 INFO - 'LANGUAGE': 'en_US:en', 05:05:54 INFO - 'LOGNAME': 'cltbld', 05:05:54 INFO - 'MAIL': '/var/mail/cltbld', 05:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:54 INFO - 'MOZ_NO_REMOTE': '1', 05:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:54 INFO - 'NO_EM_RESTART': '1', 05:05:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:54 INFO - 'PWD': '/builds/slave/test', 05:05:54 INFO - 'SHELL': '/bin/bash', 05:05:54 INFO - 'SHLVL': '1', 05:05:54 INFO - 'TERM': 'linux', 05:05:54 INFO - 'TMOUT': '86400', 05:05:54 INFO - 'USER': 'cltbld', 05:05:54 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:54 INFO - '_': '/tools/buildbot/bin/python'} 05:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:54 INFO - Downloading/unpacking jsonschema==2.5.1 05:05:54 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 05:05:54 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 05:05:54 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 05:05:54 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 05:05:54 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 05:05:54 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 05:05:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05:56 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:56 INFO - warning: no files found matching '*.txt' 05:05:56 INFO - no previously-included directories found matching 'build' 05:05:56 INFO - no previously-included directories found matching 'dist' 05:05:56 INFO - no previously-included directories found matching '.git*' 05:05:56 INFO - Installing collected packages: jsonschema, functools32 05:05:56 INFO - Running setup.py install for functools32 05:05:56 INFO - warning: no files found matching '*.txt' 05:05:56 INFO - no previously-included directories found matching 'build' 05:05:56 INFO - no previously-included directories found matching 'dist' 05:05:56 INFO - no previously-included directories found matching '.git*' 05:05:56 INFO - Successfully installed jsonschema functools32 05:05:56 INFO - Cleaning up... 05:05:56 INFO - Return code: 0 05:05:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:05: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 05:05: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 05:05:56 INFO - Using env: {'DISPLAY': ':0', 05:05:56 INFO - 'HOME': '/home/cltbld', 05:05:56 INFO - 'LANG': 'en_US.UTF-8', 05:05:56 INFO - 'LANGUAGE': 'en_US:en', 05:05:56 INFO - 'LOGNAME': 'cltbld', 05:05:56 INFO - 'MAIL': '/var/mail/cltbld', 05:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:56 INFO - 'MOZ_NO_REMOTE': '1', 05:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:56 INFO - 'NO_EM_RESTART': '1', 05:05:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:56 INFO - 'PWD': '/builds/slave/test', 05:05:56 INFO - 'SHELL': '/bin/bash', 05:05:56 INFO - 'SHLVL': '1', 05:05:56 INFO - 'TERM': 'linux', 05:05:56 INFO - 'TMOUT': '86400', 05:05:56 INFO - 'USER': 'cltbld', 05:05:56 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:56 INFO - '_': '/tools/buildbot/bin/python'} 05:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:05:57 INFO - Cleaning up... 05:05:57 INFO - Return code: 0 05:05:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:57 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')]} 05:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:57 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')]} 05:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:57 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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:05:57 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 05:05:57 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 05:05:57 INFO - Using env: {'DISPLAY': ':0', 05:05:57 INFO - 'HOME': '/home/cltbld', 05:05:57 INFO - 'LANG': 'en_US.UTF-8', 05:05:57 INFO - 'LANGUAGE': 'en_US:en', 05:05:57 INFO - 'LOGNAME': 'cltbld', 05:05:57 INFO - 'MAIL': '/var/mail/cltbld', 05:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:57 INFO - 'MOZ_NO_REMOTE': '1', 05:05:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:57 INFO - 'NO_EM_RESTART': '1', 05:05:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:57 INFO - 'PWD': '/builds/slave/test', 05:05:57 INFO - 'SHELL': '/bin/bash', 05:05:57 INFO - 'SHLVL': '1', 05:05:57 INFO - 'TERM': 'linux', 05:05:57 INFO - 'TMOUT': '86400', 05:05:57 INFO - 'USER': 'cltbld', 05:05:57 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:57 INFO - '_': '/tools/buildbot/bin/python'} 05:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:57 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 05:05:57 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 05:05:57 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 05:05:57 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 05:05:57 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 05:05:57 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 05:05:59 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:05: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 05:06:00 INFO - Downloading docopt-0.6.1.tar.gz 05:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:00 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:00 INFO - Running setup.py install for blobuploader 05:06:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:06:00 INFO - Running setup.py install for requests 05:06:00 INFO - Running setup.py install for docopt 05:06:00 INFO - Successfully installed blobuploader requests docopt 05:06:00 INFO - Cleaning up... 05:06:00 INFO - Return code: 0 05:06:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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')]} 05:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:06: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 05:06: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 05:06:00 INFO - Using env: {'DISPLAY': ':0', 05:06:00 INFO - 'HOME': '/home/cltbld', 05:06:00 INFO - 'LANG': 'en_US.UTF-8', 05:06:00 INFO - 'LANGUAGE': 'en_US:en', 05:06:00 INFO - 'LOGNAME': 'cltbld', 05:06:00 INFO - 'MAIL': '/var/mail/cltbld', 05:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:00 INFO - 'MOZ_NO_REMOTE': '1', 05:06:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:00 INFO - 'NO_EM_RESTART': '1', 05:06:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:00 INFO - 'PWD': '/builds/slave/test', 05:06:00 INFO - 'SHELL': '/bin/bash', 05:06:00 INFO - 'SHLVL': '1', 05:06:00 INFO - 'TERM': 'linux', 05:06:00 INFO - 'TMOUT': '86400', 05:06:00 INFO - 'USER': 'cltbld', 05:06:00 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:00 INFO - '_': '/tools/buildbot/bin/python'} 05:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:00 INFO - Running setup.py (path:/tmp/pip-6ez0CG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:01 INFO - Running setup.py (path:/tmp/pip-BAeuRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:01 INFO - Running setup.py (path:/tmp/pip-5toQmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:01 INFO - Running setup.py (path:/tmp/pip-h_8X7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:01 INFO - Running setup.py (path:/tmp/pip-vVvHDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:01 INFO - Running setup.py (path:/tmp/pip-z6e72C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:01 INFO - Running setup.py (path:/tmp/pip-irKRJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:01 INFO - Running setup.py (path:/tmp/pip-uXjP8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:01 INFO - Running setup.py (path:/tmp/pip-puk2GP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:01 INFO - Running setup.py (path:/tmp/pip-5R5zO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:01 INFO - Running setup.py (path:/tmp/pip-vho8Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:01 INFO - Running setup.py (path:/tmp/pip-glnpBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:01 INFO - Running setup.py (path:/tmp/pip-lY9z5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:01 INFO - Running setup.py (path:/tmp/pip-2zX7_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:01 INFO - Running setup.py (path:/tmp/pip-8wiY1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:02 INFO - Running setup.py (path:/tmp/pip-6ttzpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:02 INFO - Running setup.py (path:/tmp/pip-LwsqKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:02 INFO - Running setup.py install for manifestparser 05:06:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Running setup.py install for mozcrash 05:06:02 INFO - Running setup.py install for mozdebug 05:06:02 INFO - Running setup.py install for mozdevice 05:06:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Running setup.py install for mozfile 05:06:02 INFO - Running setup.py install for mozhttpd 05:06:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Running setup.py install for mozinfo 05:06:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Running setup.py install for mozInstall 05:06:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Running setup.py install for mozleak 05:06:03 INFO - Running setup.py install for mozlog 05:06:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:06:03 INFO - Running setup.py install for moznetwork 05:06:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:06:03 INFO - Running setup.py install for mozprocess 05:06:03 INFO - Running setup.py install for mozprofile 05:06:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:06:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:06:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:06:03 INFO - Running setup.py install for mozrunner 05:06:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:06:03 INFO - Running setup.py install for mozscreenshot 05:06:03 INFO - Running setup.py install for moztest 05:06:03 INFO - Running setup.py install for mozversion 05:06:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:06:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:03 INFO - Cleaning up... 05:06:03 INFO - Return code: 0 05:06:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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')]} 05:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:06: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 05:06: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 05:06:03 INFO - Using env: {'DISPLAY': ':0', 05:06:03 INFO - 'HOME': '/home/cltbld', 05:06:03 INFO - 'LANG': 'en_US.UTF-8', 05:06:03 INFO - 'LANGUAGE': 'en_US:en', 05:06:03 INFO - 'LOGNAME': 'cltbld', 05:06:03 INFO - 'MAIL': '/var/mail/cltbld', 05:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:03 INFO - 'MOZ_NO_REMOTE': '1', 05:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:03 INFO - 'NO_EM_RESTART': '1', 05:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:03 INFO - 'PWD': '/builds/slave/test', 05:06:03 INFO - 'SHELL': '/bin/bash', 05:06:03 INFO - 'SHLVL': '1', 05:06:03 INFO - 'TERM': 'linux', 05:06:03 INFO - 'TMOUT': '86400', 05:06:03 INFO - 'USER': 'cltbld', 05:06:03 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:03 INFO - '_': '/tools/buildbot/bin/python'} 05:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:04 INFO - Running setup.py (path:/tmp/pip-axNpXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:04 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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:04 INFO - Running setup.py (path:/tmp/pip-0QvWrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:04 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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:04 INFO - Running setup.py (path:/tmp/pip-IecRrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:04 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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:04 INFO - Running setup.py (path:/tmp/pip-Czk1ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:04 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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:04 INFO - Running setup.py (path:/tmp/pip-X3PoKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:04 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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:04 INFO - Running setup.py (path:/tmp/pip-xQbuHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06: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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:04 INFO - Running setup.py (path:/tmp/pip-9tqA_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06: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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:04 INFO - Running setup.py (path:/tmp/pip-0REcH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06: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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:04 INFO - Running setup.py (path:/tmp/pip-4b8hBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06: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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:04 INFO - Running setup.py (path:/tmp/pip-O40xWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06: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)) 05:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:04 INFO - Running setup.py (path:/tmp/pip-1kL19G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:05 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)) 05:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:05 INFO - Running setup.py (path:/tmp/pip-r6TR8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:05 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)) 05:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:05 INFO - Running setup.py (path:/tmp/pip-Pz9uCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:05 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)) 05:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:05 INFO - Running setup.py (path:/tmp/pip-OGn0uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:05 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)) 05:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:05 INFO - Running setup.py (path:/tmp/pip-H67P3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:05 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)) 05:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:05 INFO - Running setup.py (path:/tmp/pip-SJJ2Bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:05 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)) 05:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:05 INFO - Running setup.py (path:/tmp/pip-grbJ6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:05 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)) 05:06:05 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)) 05:06:05 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)) 05:06:05 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)) 05:06:05 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)) 05:06:05 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)) 05:06:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:05 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 05:06:05 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 05:06:05 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 05:06:05 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 05:06:05 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 05:06:05 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 05:06:07 INFO - Downloading blessings-1.6.tar.gz 05:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:07 INFO - Installing collected packages: blessings 05:06:07 INFO - Running setup.py install for blessings 05:06:07 INFO - Successfully installed blessings 05:06:07 INFO - Cleaning up... 05:06:07 INFO - Return code: 0 05:06:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:07 INFO - Reading from file tmpfile_stdout 05:06:07 INFO - Current package versions: 05:06:07 INFO - argparse == 1.2.1 05:06:07 INFO - blessings == 1.6 05:06:07 INFO - blobuploader == 1.2.4 05:06:07 INFO - docopt == 0.6.1 05:06:07 INFO - functools32 == 3.2.3-2 05:06:07 INFO - jsonschema == 2.5.1 05:06:07 INFO - manifestparser == 1.1 05:06:07 INFO - mozInstall == 1.12 05:06:07 INFO - mozcrash == 1.0 05:06:07 INFO - mozdebug == 0.1 05:06:07 INFO - mozdevice == 0.49 05:06:07 INFO - mozfile == 1.2 05:06:07 INFO - mozhttpd == 0.7 05:06:07 INFO - mozinfo == 0.9 05:06:07 INFO - mozleak == 0.1 05:06:07 INFO - mozlog == 3.4 05:06:07 INFO - moznetwork == 0.27 05:06:07 INFO - mozprocess == 0.25 05:06:07 INFO - mozprofile == 0.28 05:06:07 INFO - mozrunner == 6.13 05:06:07 INFO - mozscreenshot == 0.1 05:06:07 INFO - mozsystemmonitor == 0.3 05:06:07 INFO - moztest == 0.8 05:06:07 INFO - mozversion == 1.4 05:06:07 INFO - psutil == 3.1.1 05:06:07 INFO - requests == 1.2.3 05:06:07 INFO - wsgiref == 0.1.2 05:06:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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')]} 05:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:06: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 05:06: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 05:06:07 INFO - Using env: {'DISPLAY': ':0', 05:06:07 INFO - 'HOME': '/home/cltbld', 05:06:07 INFO - 'LANG': 'en_US.UTF-8', 05:06:07 INFO - 'LANGUAGE': 'en_US:en', 05:06:07 INFO - 'LOGNAME': 'cltbld', 05:06:07 INFO - 'MAIL': '/var/mail/cltbld', 05:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:07 INFO - 'MOZ_NO_REMOTE': '1', 05:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:07 INFO - 'NO_EM_RESTART': '1', 05:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:07 INFO - 'PWD': '/builds/slave/test', 05:06:07 INFO - 'SHELL': '/bin/bash', 05:06:07 INFO - 'SHLVL': '1', 05:06:07 INFO - 'TERM': 'linux', 05:06:07 INFO - 'TMOUT': '86400', 05:06:07 INFO - 'USER': 'cltbld', 05:06:07 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:07 INFO - '_': '/tools/buildbot/bin/python'} 05:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 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)) 05:06:08 INFO - Cleaning up... 05:06:08 INFO - Return code: 0 05:06:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15edb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', '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 05:06:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:06:08 INFO - Using env: {'DISPLAY': ':0', 05:06:08 INFO - 'HOME': '/home/cltbld', 05:06:08 INFO - 'LANG': 'en_US.UTF-8', 05:06:08 INFO - 'LANGUAGE': 'en_US:en', 05:06:08 INFO - 'LOGNAME': 'cltbld', 05:06:08 INFO - 'MAIL': '/var/mail/cltbld', 05:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:08 INFO - 'MOZ_NO_REMOTE': '1', 05:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:08 INFO - 'NO_EM_RESTART': '1', 05:06:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:08 INFO - 'PWD': '/builds/slave/test', 05:06:08 INFO - 'SHELL': '/bin/bash', 05:06:08 INFO - 'SHLVL': '1', 05:06:08 INFO - 'TERM': 'linux', 05:06:08 INFO - 'TMOUT': '86400', 05:06:08 INFO - 'USER': 'cltbld', 05:06:08 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:08 INFO - '_': '/tools/buildbot/bin/python'} 05:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:06:08 INFO - Cleaning up... 05:06:08 INFO - Return code: 0 05:06:08 INFO - Running post-action listener: _resource_record_post_action 05:06:08 INFO - Running post-action listener: _start_resource_monitoring 05:06:08 INFO - Starting resource monitoring. 05:06:08 INFO - [mozharness: 2017-04-06 12:06:08.441220Z] Finished create-virtualenv step (success) 05:06:08 INFO - [mozharness: 2017-04-06 12:06:08.441683Z] Running install step. 05:06:08 INFO - Running pre-action listener: _resource_record_pre_action 05:06:08 INFO - Running main action method: install 05:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:08 INFO - Reading from file tmpfile_stdout 05:06:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:08 INFO - Reading from file tmpfile_stdout 05:06:08 INFO - Output received: 05:06:08 INFO - Usage: mozinstall [options] installer 05:06:08 INFO - Options: 05:06:08 INFO - -h, --help show this help message and exit 05:06:08 INFO - -d DEST, --destination=DEST 05:06:08 INFO - Directory to install application into. [default: 05:06:08 INFO - "/builds/slave/test"] 05:06:08 INFO - --app=APP Application being installed. [default: firefox] 05:06:08 INFO - mkdir: /builds/slave/test/build/application 05:06: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'] 05:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:06:25 INFO - Reading from file tmpfile_stdout 05:06:25 INFO - Output received: 05:06:25 INFO - /builds/slave/test/build/application/firefox/firefox 05:06:25 INFO - Running post-action listener: _resource_record_post_action 05:06:25 INFO - [mozharness: 2017-04-06 12:06:25.305546Z] Finished install step (success) 05:06:25 INFO - [mozharness: 2017-04-06 12:06:25.305900Z] Running run-tests step. 05:06:25 INFO - Running pre-action listener: _resource_record_pre_action 05:06:25 INFO - Running main action method: run_tests 05:06:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:25 INFO - Python 2.7.3 05:06:25 INFO - Return code: 0 05:06:25 INFO - grabbing minidump binary from tooltool 05:06:25 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')]} 05:06:25 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 0x15eb290>, '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 0x15ebb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15ec040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:25 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 05:06:25 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 05:06:25 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 05:06:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:25 INFO - Return code: 0 05:06:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:06:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:25 INFO - ENV: RUST_BACKTRACE is now 1 05:06:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:06:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:25 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/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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 05:06:25 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/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-074 --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 05:06:25 INFO - Using env: {'DISPLAY': ':0', 05:06:25 INFO - 'HOME': '/home/cltbld', 05:06:25 INFO - 'LANG': 'en_US.UTF-8', 05:06:25 INFO - 'LANGUAGE': 'en_US:en', 05:06:25 INFO - 'LOGNAME': 'cltbld', 05:06:25 INFO - 'MAIL': '/var/mail/cltbld', 05:06:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:25 INFO - 'MOZ_NO_REMOTE': '1', 05:06:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:25 INFO - 'NO_EM_RESTART': '1', 05:06:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:25 INFO - 'PWD': '/builds/slave/test', 05:06:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:25 INFO - 'RUST_BACKTRACE': '1', 05:06:25 INFO - 'SHELL': '/bin/bash', 05:06:25 INFO - 'SHLVL': '1', 05:06:25 INFO - 'TERM': 'linux', 05:06:25 INFO - 'TMOUT': '86400', 05:06:25 INFO - 'USER': 'cltbld', 05:06:25 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865', 05:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:25 INFO - '_': '/tools/buildbot/bin/python'} 05:06:25 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/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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 05:06:25 INFO - mozversion application_buildid: 20170406110941 05:06:25 INFO - mozversion application_changeset: 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a 05:06:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:06:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:25 INFO - mozversion application_name: Firefox 05:06:25 INFO - mozversion application_remotingname: firefox-dev 05:06:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:06:25 INFO - mozversion application_vendor: Mozilla 05:06:25 INFO - mozversion application_version: 54.0a2 05:06:25 INFO - mozversion platform_buildid: 20170406110941 05:06:25 INFO - mozversion platform_changeset: 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a 05:06:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:06:25 INFO - mozversion platform_version: 54.0a2 05:06:25 INFO - using testdate: 1491480385 05:06:25 INFO - actual date: 1491480385 05:06:25 INFO - starting webserver on 'localhost:54342' 05:06:25 INFO - SUITE-START | Running 2 tests 05:06:25 INFO - TEST-START | dromaeo_css 05:06:25 INFO - operating with platform_type : linux_ 05:06:25 INFO - Initialising browser for dromaeo_css test... 05:06:25 INFO - TEST-INFO | started process 10540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd5d8Fv/profile http://localhost:54342/getInfo.html) 05:06:29 INFO - PROCESS | 10540 | __metrics Screen width/height:1600/1200 05:06:29 INFO - PROCESS | 10540 | colorDepth:24 05:06:29 INFO - PROCESS | 10540 | Browser inner width/height: 1024/697 05:06:29 INFO - PROCESS | 10540 | __metrics 05:06:30 INFO - PROCESS | 10540 | 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" 05:06:30 INFO - PROCESS | 10540 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:31 INFO - TEST-INFO | 10540: exit 0 05:06:31 INFO - Browser initialized. 05:06:31 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:31 INFO - TEST-INFO | started process 10631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd5d8Fv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:43 INFO - PROCESS | 10631 | RSS: Main: 139677696 05:06:43 INFO - PROCESS | 10631 | 05:09:44 INFO - PROCESS | 10631 | Cycle 1(1): loaded http://localhost:54342/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54342/tests/dromaeo/cssquery-ext.html) 05:09:46 INFO - PROCESS | 10631 | RSS: Main: 280043520 05:09:46 INFO - PROCESS | 10631 | 05:11:33 INFO - PROCESS | 10631 | ************************* 05:11:33 INFO - PROCESS | 10631 | A coding exception was thrown and uncaught in a Task. 05:11:33 INFO - PROCESS | 10631 | 05:11:33 ERROR - PROCESS | 10631 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:11:33 INFO - PROCESS | 10631 | Full stack: 05:11:33 INFO - PROCESS | 10631 | ************************* 05:11:33 INFO - PROCESS | 10631 | ************************* 05:11:33 INFO - PROCESS | 10631 | A coding exception was thrown and uncaught in a Task. 05:11:33 INFO - PROCESS | 10631 | 05:11:33 ERROR - PROCESS | 10631 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:11:33 INFO - PROCESS | 10631 | Full stack: 05:11:33 INFO - PROCESS | 10631 | ************************* 05:11:33 INFO - PROCESS | 10631 | ************************* 05:11:33 INFO - PROCESS | 10631 | A coding exception was thrown and uncaught in a Task. 05:11:33 INFO - PROCESS | 10631 | 05:11:33 ERROR - PROCESS | 10631 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:11:33 INFO - PROCESS | 10631 | Full stack: 05:11:33 INFO - PROCESS | 10631 | ************************* 05:11:33 INFO - PROCESS | 10631 | ************************* 05:11:33 INFO - PROCESS | 10631 | A coding exception was thrown and uncaught in a Task. 05:11:33 INFO - PROCESS | 10631 | 05:11:33 ERROR - PROCESS | 10631 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:11:33 INFO - PROCESS | 10631 | Full stack: 05:11:33 INFO - PROCESS | 10631 | ************************* 05:12:48 INFO - PROCESS | 10631 | Cycle 1(1): loaded http://localhost:54342/tests/dromaeo/cssquery-ext.html (next: http://localhost:54342/tests/dromaeo/cssquery-jquery.html) 05:12:48 INFO - PROCESS | 10631 | RSS: Main: 224182272 05:12:48 INFO - PROCESS | 10631 | 05:15:50 INFO - PROCESS | 10631 | Cycle 1(1): loaded http://localhost:54342/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54342/tests/dromaeo/cssquery-mootools.html) 05:15:51 INFO - PROCESS | 10631 | RSS: Main: 169910272 05:15:51 INFO - PROCESS | 10631 | 05:18:53 INFO - PROCESS | 10631 | Cycle 1(1): loaded http://localhost:54342/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54342/tests/dromaeo/cssquery-prototype.html) 05:18:53 INFO - PROCESS | 10631 | RSS: Main: 197595136 05:18:53 INFO - PROCESS | 10631 | 05:21:56 INFO - PROCESS | 10631 | Cycle 1(1): loaded http://localhost:54342/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54342/tests/dromaeo/cssquery-yui.html) 05:21:57 INFO - PROCESS | 10631 | RSS: Main: 175013888 05:21:57 INFO - PROCESS | 10631 | 05:24:59 INFO - PROCESS | 10631 | Cycle 1(1): loaded http://localhost:54342/tests/dromaeo/cssquery-yui.html (next: http://localhost:54342/tests/dromaeo/cssquery-dojo.html) 05:25:00 INFO - PROCESS | 10631 | RSS: Main: 268808192 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - PROCESS | 10631 | __start_tp_report 05:25:00 INFO - PROCESS | 10631 | _x_x_mozilla_page_load 05:25:00 INFO - PROCESS | 10631 | _x_x_mozilla_page_load_details 05:25:00 INFO - PROCESS | 10631 | |i|pagename|runs| 05:25:00 INFO - PROCESS | 10631 | |0;dojo.html;3361.394948909197;3385.1198688341033;3386.661333866612;3398;3442.845071971761;5604.915926261105;5667.461591148839;5672;5692.37383887773;5694.487496125353;5693.715314234282;5728.140778883169;5748.798792042279;5754.280714910637;5794.188813566104;5178.792848286064;5187.377514698242;5190.506901844319;5192.61055420845;5239.240310155025;5102.69383836971;5112.182050871861;5279.815206467774;5282.366116066078;5309.0709125903;5153.845384638448;5156.484351564851;5228.032813929416;5235;5245.79016839326;5232.241325007872;5264.657797243186;5271.683698978035;5273.2617433559335;5300;5034.068697290976;5120.743962801882;5187.610929180327;5236.371635403738;5237.214417837316;5552.08390615548;5594.468525490072;5636.098224284095;5642.238297829823;5647.717614119319;6059.39406059395;6096.4817990470865;6109.5723299369065;6118.4799292060225;6119.26568811741;6119.87760244797;6136.539759518054;6141.078838174265;6143.846403839895;6144.385561443864;5555.527780138693;5585.413531579166;5674.773009079632;5704.914426283609;5715.571332150105;6242.563020588515;6248.937510624835;6249.437550620466;6254.15568898202;6265.906011409833;5962.880742385128;5964.224650795456;6020.548458865515;6021.969890150521;6070.028795392805;5869.911951320733;5904.232449781587;6019.0068638675;6078.817635470943;6086.360932102066;6006.759729610854;6053.213082299273;6057.3336932937345;6073.605215660968;6090.878182436327;5876.294844618673;5913.674747888907;5996.070609055516;6010.66941318223;6061.484773794277;4618.053299073749;4666.859994200179;4683.742394168354;4685.226937555265;4691.319758634979;4067.7762723049923;4071.2875246831686;4158.106007208404;4168.624823765815;4204.222218889515;5715.628484148517;5835.44563266489;5909.8227053188475;5949.077892926603;5964.7315870785915;6170.352113028068;6175.69121543921;6176.073588961692;6179.62922224668;6180.381961803783;5706.714664266853;5923.851903702442;5932.7330270137945;5933.050711886078;5933.6736479493175;5155.7679904404395;5158.613104017214;5200.167973124358;5240.4497527759795;5247.685138891678;6381.4575761059905;6405.551611387158;6410.679466026773;6422.518311126684;6424.3254458282145;6058;6117;6121.969390153199;6132.693365331805;6134.907976380268;6134.252905685562;6169.660668663267;6327.841803954938;6337.397947195009;6444.162258906312;6046.969765151146;6050.364711705208;6052.152698622109;6062.605930614496;6093.664848433379;6151.477124444473;6159.076138579249;6164.660943648142;6165.568410211242;6166.414190651881;5681.687507187145;5794.82615521535;5908.822735317947;5965.522758179249;6005.129256257906;5997.250343707036;6000.51995840323;6001.609895356788;6021.187139736079;6030.698465076816;5902.291724993029;5947.732352044255;5953.1070339449425;5969.582129250911;5972.223610930725;5774.855628609151;5806.5064469518875;5835.037218859011;5840.8247752567495;5843.2695913010875;5799.565032622654;5856.385079566755;5915.349311575809;5915.467607915309;5922.674252915958 05:25:00 INFO - PROCESS | 10631 | |1;ext.html;760.6272926266128;809.2676128104066;840.0717207485728;892.023234558159;922.981540369193;31679.683203167962;43543;44317.7784111079;44398.556014439884;44443.333349999775;44839.551604483946;44933;44936.550634493644;45130;45161.774191129;7744.767656970272;7921.56431396273;9139.680111196127;9267.258619310473;9267.67563135287;10356;10639.627613033545;10640.574377024948;10644.946775266113;10952.069074128669;10678.305910115856;10687.358758474478;10726.624568140116;10728.302660327094;10736.30214036085;10615.681529554126;10661.200409969244;10704.357738535635;10704.51829667663;10718.464076796128;10628.202884783672;10648.78702425947;10648.988346107108;10697.304675196086;10697.893021069845;41534.37698434526;41627.58372416267;41764.79117604423;41797.582024179675;41854;4197.790110494463;4201.306784380574;4234.449521562177;4324.6972711910175;4350.630196433307;29745.553816692765;30238.09285721432;30263.394732105455;30682.693173068208;31570.526442103386;3959.742616729903;4146.3780432935;4222.9577704222875;4249.745015299092;4307.030918043447;248835;249434;249632;249817;249823.9999999982;54890.725546372814;55141.44858551443;55165.72417137888;55185.44814551803;55219;57609.423905760406;57674.711626441596;57857;57932;58098.41901581015;16246.837531624533;16656.58358541022;16662.166891655368;16662.50012499627;16663.833361666228;11846.170768046153;11929.642110736691;13401.798973015413;14493.20287384204;14495.130292182503;12987.960963122927;13012.674683132807;13071.477140914447;13190.934045329905;13231.933840330737;5413.188021796762;5497.312835895513;5500.257465242141;5505.421930697299;5522.088855338904;990.4503000834462;994.2046362909643;996.8803743550675;997.7555498902815;1018.277023313441;26132.608010879852;26227;26232.475350492885;26413.207603771916;26612.201633951012;1064.1858977881927;1083.0523292119397;1092.0335503079832;1105.4307031878589;1113.0705860606322;14277.714445711235;14466.349014294172;14535.27323633814;14766.261686915612;14782.63043423923;338111;340584;340781;341367;341776;57403;57469.71265143731;57520.4247957515;57522;57561.42438575644;1508.0951429142426;1513.8940274180702;1552.8524790144918;1555.6655319106446;1564.8278689344163;24085.638715419635;27626.723732762413;28304.931480843046;28720;30476.238094046937;46580.7670961643;47034.76482617565;47122.057558849316;47130.764346178046;47154.764226178646;365587;367116;368653;369019;369921;29744.55383169211;29833.55249671213;30082.84958575193;30617.693823062375;33113.66886331106;40315.596844031184;40362.798186010055;40411.393829092;40584.59415405808;40914.795426022676;30973;31135.13189208655;31425.8428707855;31780.364392711555;32021.35957280888;31403.371932561677;31497.21256968594;31548.842255788575;31670.366592668477;32156.19609509781;35164.296714066084;35491;35909.64090359063;35979.28041439104;36171.457428139125;29706;29990.400191996476;30299.333536655697;30359.392812144077;30413.391732165674;23378.298651040495;23920.64119038181;24390;24468.26595202147;24691 05:25:00 INFO - PROCESS | 10631 | |2;jquery.html;420.5668161793354;465.39266257533944;469.91684167992764;470.1631096647966;485.4538644025471;19110.808891911078;19866.30467933619;20945.581088378243;21163.894180529096;21456.8927155364;21015.684764728503;21447.785522144775;21601.67597486035;21980.450488737788;21987.34037978859;6619.536632435731;6757.493188010906;6802.455803535705;6855;6929.341712537301;4146.481689788777;4368.301071828508;6731.966340168292;6842.55523390978;6847.212570554375;7333.339999400053;7577.886331705029;7588.27911348421;7614.390848732116;7689.307962283393;7484.550926944374;7534.660940257701;7543.39652827778;7548.396128309777;7645.706288113543;7246.782596522113;7365.373943214833;7368.815779605499;7482.401407887356;7546.207648196915;24422.26733198007;24733;25088;25292.114775982936;25361.873190634113;3119.5788568543185;3274.836258187081;3281.5241789940324;3329.9843547717937;3366.8653253869816;15652.68694626101;16155.676886462323;16429.342826286935;16438.671226575403;16935.83064169355;2291.163725240292;2333.626619740833;2365.3258821236036;2404.711434627856;2499.800015998734;116641;120162;120665;121306;121962;14542.21341916316;16486.422975196052;18208.90448623737;18298.817011829713;18691.626167476847;31830.204244894063;32047.839760801515;32108.518372224436;32141.839290803862;32608.510872336934;9809.117179453884;9809.117179453884;10001.899981000242;10012.69961901144;10065.194784417228;8303.543305118277;8322.750317490485;8362.163783621589;8500.192481714228;8509.404341696021;9121;9186.173244408035;9401.764955876159;9487.051294870458;9629.422234665943;3648.945265821015;3709.6104908984707;3944.692354972316;3975.9463742108364;4039.1315867088715;958.4584709639032;972.8686627305365;979.0796651147898;988.6737376665683;988.7083310423442;20079.899600502195;20232.39302820918;20361.287354942506;20527.794722052888;20598.794012059687;982.6953644370268;989.2333441582831;991.5191132300828;992.5285489392625;1003.1924300937815;10906.072983796308;11265;11324.773504529863;11328.660140195807;11362.977332040156;672758;678344;706514;724092;728648;88411;93585.5320723392;108441;116181.41909290568;120717;1265.4242319744494;1321.3813078978367;1331.7536255792515;1338.3174580963778;1372.84212315583;12910.612681619567;16588.336466541205;18970.810291896905;19120.521986950436;19126.52183695363;49359.50640493549;49425.011499770524;51305;51865.7406712964;53501.4649853512;63389;63532.04701929382;63846.36153638404;64107.35892641014;66188;10479.895201047892;11555.942220288845;11999.460024299102;12452.626421207378;12527.498900043896;14081.366338514586;14127.43490260378;14143.575692729235;14162.645933851323;14188.290585470479;12254;12280.508779648713;12366.876331236572;12440.066994975232;12656.11407201487;11230.55077796879;11404;11432.885671143182;11568.88431115678;11654.592089276835;12017.75964480688;12307.323097229739;12396.504139834666;12456.688582785502;12878.162919410208;12191.573294934635;12281.44733487013;12415.503379864704;12487.381836806804;12647.747045059232;12228.144029917821;12343.629691109281;12409.317487538272;12676.809847852106;12688.555900543437 05:25:00 INFO - PROCESS | 10631 | |3;mootools.html;1518.8784897208225;1525.344102036124;1543.3055125193662;1549.3570168380122;1551.2243878060963;10834.241603087787;11492.482838272277;11507.597234096818;11525.19323647345;11529.308241505516;11242.156838237124;11362.943185284063;11652.126090543201;11667.649970500877;11684.532618695262;2329.557384097016;2388.235764555343;2399.748026457223;2400.975990240093;2401.4836810085826;2337.801286890616;2419.4798118404537;2423;2423.321469988406;2425.7938075263537;2336.485973085927;2367.266147494274;2387.689600351952;2407.3500154958238;2411.7347091819884;2275.2946586558055;2331.5453486570127;2335.1710142899196;2343.250159948817;2345.3901985483812;2268.3648578398074;2282.8744419056943;2287.0165828693657;2289.828262880274;2291.3126062181445;11145;11388.088952883749;11388.54445822166;11398.658040258806;11399.316041037564;5593.356763972114;5716.971415142898;5723.885522289573;5724.2558467399385;5729.799457018984;3324.2354258520677;3398.150462384404;3466.532018177539;3494.248736521635;3528.4530897710897;1235.2773627427966;1272.1540175783093;1279.4306533592555;1280.532605598959;1342.7113170668354;14955.102693838404;15281;16422.589435263973;17038.74441883373;17536.824631753647;4797.352357431771;4802.159622066124;4889.266610008527;4892.143874821892;4903.8528844134735;14330.21183834899;14445.566633001026;14450.783238251435;14453.78319325211;14454.494092706705;5000.6999580024585;5148.150555158431;5646.463585959327;5728.083506639001;5794.333651630032;5269.314989051399;5292.655977361459;5372.059889519397;5454.181872719124;5459.208414779835;4276.593723596254;4279.529251782302;4295.78521073945;4333.479982402132;4340.218760623118;1128.4132251229314;1159.084323384517;1171.619223752284;1177.205386364218;1180.976380472403;850.4897061762892;866.2853146154448;872.9607167677469;877.1929824561419;898.1377877237794;7142.892856607155;7229;7230.349268565854;7246;7328.487005909536;1021.0084452554088;1048.4967215736337;1051.6582110813863;1063.3672964586058;1076.05844885725;4573.176828170961;4575.771211439414;4635.884102897455;4672.813087476531;4726.196546587088;13337.399817008063;13782.104163229358;14368.497102601568;14390.568282951319;14395.352209150613;14095.71808563823;14108.294585270696;14116.788248176286;14129.364178611988;14141.151530908179;10283.53724082418;10464.162866970777;10466.267361284788;10492.317999330018;10506.842397364046;6613.305602911625;6744.696488657924;6771.559848609824;6835.658217089225;6912.205096413775;12856.871431285568;12978.156419832681;13087.214767114003;13168.73662526725;13205.075644704779;13246.403911824184;13264.071514994244;13309.134906231064;13354.465821367037;13394.062415630813;9899.554520046471;9922.900770992197;9933.155681766842;10040.196784257389;10062.094411502707;10744.086752626114;10751.301165424222;10832;10852.565897364017;10857.511411986638;9274.489903055397;9315.673951411669;9402.482863442576;9497.572609232462;9547.093026162504;9033.548322583712;9173.587188576663;9237.12247336502;9255.583498742439;9315.34792564514;8187.4678145920325;8268.958655206927;8326.875096873673;8329.416940814328;8338.332933365438;6138.1713468681155;6260.718267678057;6271.090691849748;6290.24517057956;6397.584157029778;6140.473017325276;6318.557700961072;6329.8734025320155;6468.2561505427475;6553.836154095995 05:25:00 INFO - PROCESS | 10631 | |4;prototype.html;424.6284501061571;424.7769920791586;442.6016585073435;446.1055583554972;446.4508654355143;5335.733213339332;5352.892942141155;5356.3036805215315;5377.327834020747;5384.846061575372;5109.824740309722;5225.764840582174;5236.2931004314405;5259.894802103965;5340.332458442695;4464.665150113747;4471.508134105262;4477.798499067533;4482.215612267856;4492.932606010912;4490.595846373826;4524.45706515217;4532.784510405339;4532.796024178903;4536.614387777043;4230.640395566381;4468.4414448193975;4491.8096704373365;4492.483364413101;4517.932231016521;4444.044710267668;4449.844255451044;4458.3535387368975;4476.455622810142;4491.483479399878;4204.684648651333;4208.558101399371;4231.809568569422;4246.384274280243;4278.507971583276;5063.746812659352;5079.739515915419;5124.795008199668;5179.533841954205;5222.164453687392;5152.690838549699;5177.37871455424;5187.766550505236;5190.584753219733;5195.298634684307;5532.763333449896;5556.9444305556835;5557.6109672322955;5574.832755017356;5598.44015598441;4390.604845563883;4579.175748365293;4595.195840727859;4597.517260687613;4676.2050451423;5493.917591236134;5569.554435645138;5630.451625802896;5737.770489180428;5778.219940308089;4658.86023419298;4713.9764301178275;4714.622830173578;4715.646326525456;4755.690880092783;4600.723956562617;4676.602488788423;4817.638677099231;4823.9517604824205;4836.322914791932;5006.6245031622775;5101.464346243591;5105.744712764347;5123.333966584395;5153.484651534816;5528.612997090246;5528.838943821762;5530.889382212334;5545.057340252167;5561.249231353715;1663.6256842210648;1667.7498375243567;1714.802797678258;1737.530866665993;1771.353455988555;1423.6583220027119;1445.7903603977365;1470.9485168019069;1479.4452080469823;1493.27576125579;5387.437643083462;5416.158506107942;5422.48486393792;5434;5448.400675925645;5685;5714.771409143587;5715.256846661725;5716.28546431696;5720.8283751487525;5161.845144645667;5314.654547454403;5415.431379705153;5422.972885135549;5448.482394172547;2990.3983534325275;3004.038707613586;3004.849757512159;3013.909582712522;3037.9544306835414;5744.569157313134;5907.793227237026;5917.751254523254;5947.970260148758;5971.28344598651;5799.82600521985;5824.592278540462;5826.650400975955;5838.7080645967535;5861.17943487912;5893.528517718572;5904.439078287556;5976.8804623908145;5988.4909782668965;5988.580799344091;5451.536619387396;5652.738904438258;5685.147227915681;5705.515031222227;5719.971400142973;5374.291738700284;5491.764353020539;5501.367342755474;5630.0991841305195;5678.914816277676;5974.4324289192455;5975.312839023568;5979.342272349951;5984.521238300838;5994.430529099728;4522.547745225583;4716.221823399101;4720.929186062281;4730.4796472387325;4751.239801631722;5593.328800543961;5652.463016013472;5699.919529371832;5729.713514324184;5739.770409183586;5601.80393686219;5603.62916924783;5609;5614.550835933197;5615.354234262949;5392.272043274108;5580.967521008626;5591.058303895662;5611.354694210055;5628.605997580294;5548.195511650707;5550.55595552363;5567.415421380859;5577.295011919135;5584.636998595079;5549.116777236007;5559.916601251065;5571.554275658019;5575.303087114111;5578.63738856973;5288.65623734456;5442.047641662614;5476.315460567429;5478.506934375766;5503.156442591617 05:25:00 INFO - PROCESS | 10631 | |5;yui.html;842.9620667069981;844.6536919862854;873.7815546113471;883.3066043156124;890.8040231149149;16013.27940242689;16926.407575734822;17087.572810679725;17092.743608845878;17213.913930430346;16484.67030659386;16648.916755416238;16660.83339166608;16697.749533757004;16722.16389180539;888.1207604471591;890.5636238243246;892.3129190523293;897.9730608081768;904.0507467159489;898.8112496375769;904.5612877754317;904.7331037343966;906.9319801014917;908.5956749246587;958.4776296918196;960.0879164793439;961.1349785193312;961.2262128986198;961.6009356117203;944.0653752784729;944.0936700767237;944.5654998700606;944.7354740672588;945.546137853834;702.7821375373651;703.1281211298004;706.1737766812842;706.5795851468368;706.86569551785;16815.579610509714;17058.48824535266;17234.3967961122;17298;17308.134593270286;15124.470643527533;15195.848041519665;15339.156346400943;15451.304691288919;15707.450239241685;12856;13147.86852131476;13286.468541258339;13327.466901323935;13502.324883755773;3774.1647665311625;3855.8072096395067;3859.8842034739005;3878.8836334909997;3962.306596345628;67080.66459667719;98005.50997244968;98223.50888245585;98430.50784746102;98506;12269.386530673431;12512;12554;12562.81155782664;12839.358032098358;20751.68872466914;20904.79095209059;20952.790472095083;21154.57690846205;21164.78835211659;8640.092790257058;8679.262262707614;8811.400065586171;8819.823603527893;8871.024187339388;4916.704997700078;5005.374328208974;5031.496850314939;5045.2432135180015;5077.289179514871;3623.8912832615065;3724.7020238380865;3725.925481490355;3727.4595183698752;3739.8317075731657;613.408061220915;632.9113924050637;637.8564822914807;639.1563136659657;639.6737663791407;856.7429771068654;865.251557402842;873.8121303919561;882.8455020371538;901.5987885391069;10768.461576921123;11626.825597616042;11630.76738465226;11768.88231117695;12257.754844903053;822.5270469480073;824.0729179672868;827.5241736001702;842.3598065470287;846.3398549131687;5096.133657278272;5100.285959965608;5102.540771330598;5114.28400023997;5150.896982060338;78977;80499;80502.59748701219;81597.59201204075;81786;69818.65090674514;70049.64975175091;71429;73106.63446682732;74180.62909685525;62428;63104.36895630985;63342;63726.68136659287;63755.36244637587;9780.266480013885;10029.54867031;10053.949730251303;10059.597616095567;10065.194784417376;43900.56099438965;44096.338554922346;44551.55448445474;44828.55171448244;45668.77165614151;49931;50351;50379;51444.22833657573;52282.47717522776;11998.280103193836;12386.81419778686;12426.5650702225;12468.376581170724;12553.811692824798;13144.934275328562;13153.145045572008;13209.405576748877;13282.734345313233;13291;11526.423678815858;11560.595379161688;11635.709107272047;11685.591004314809;13286.20282783036;15464.690706186038;15603.453879114177;16038.67922641564;16040.919795400949;16214.27035783369;13876.58370248894;13940;14098.647533811736;14257.786133207803;14258;10764.569417223223;10780.329048997419;10859.185561083108;11160.274582152135;11354.205205635526;12750.298733569743;12847.164934279243;12852.228866268053;13158.539451119164;13205.603831885059 05:25:00 INFO - PROCESS | 10631 | __end_tp_report 05:25:00 INFO - PROCESS | 10631 | __start_cc_report 05:25:00 INFO - PROCESS | 10631 | _x_x_mozilla_cycle_collect,1405 05:25:00 INFO - PROCESS | 10631 | __end_cc_report 05:25:00 INFO - PROCESS | 10631 | __startTimestamp1491481500059__endTimestamp 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - PROCESS | 10631 | ------- Summary: start ------- 05:25:00 INFO - PROCESS | 10631 | Number of tests: 6 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - PROCESS | 10631 | [#0] dojo.html Cycles:165 Average:5670.76 Median:5909.82 stddev:636.41 (10.8%) stddev-sans-first:612.03 05:25:00 INFO - PROCESS | 10631 | Values: 3361.4 3385.1 3386.7 3398.0 3442.8 5604.9 5667.5 5672.0 5692.4 5694.5 5693.7 5728.1 5748.8 5754.3 5794.2 5178.8 5187.4 5190.5 5192.6 5239.2 5102.7 5112.2 5279.8 5282.4 5309.1 5153.8 5156.5 5228.0 5235.0 5245.8 5232.2 5264.7 5271.7 5273.3 5300.0 5034.1 5120.7 5187.6 5236.4 5237.2 5552.1 5594.5 5636.1 5642.2 5647.7 6059.4 6096.5 6109.6 6118.5 6119.3 6119.9 6136.5 6141.1 6143.8 6144.4 5555.5 5585.4 5674.8 5704.9 5715.6 6242.6 6248.9 6249.4 6254.2 6265.9 5962.9 5964.2 6020.5 6022.0 6070.0 5869.9 5904.2 6019.0 6078.8 6086.4 6006.8 6053.2 6057.3 6073.6 6090.9 5876.3 5913.7 5996.1 6010.7 6061.5 4618.1 4666.9 4683.7 4685.2 4691.3 4067.8 4071.3 4158.1 4168.6 4204.2 5715.6 5835.4 5909.8 5949.1 5964.7 6170.4 6175.7 6176.1 6179.6 6180.4 5706.7 5923.9 5932.7 5933.1 5933.7 5155.8 5158.6 5200.2 5240.4 5247.7 6381.5 6405.6 6410.7 6422.5 6424.3 6058.0 6117.0 6122.0 6132.7 6134.9 6134.3 6169.7 6327.8 6337.4 6444.2 6047.0 6050.4 6052.2 6062.6 6093.7 6151.5 6159.1 6164.7 6165.6 6166.4 5681.7 5794.8 5908.8 5965.5 6005.1 5997.3 6000.5 6001.6 6021.2 6030.7 5902.3 5947.7 5953.1 5969.6 5972.2 5774.9 5806.5 5835.0 5840.8 5843.3 5799.6 5856.4 5915.3 5915.5 5922.7 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - PROCESS | 10631 | [#1] ext.html Cycles:180 Average:48339.97 Median:31620.45 stddev:84920.98 (268.6%) stddev-sans-first:85084.07 05:25:00 INFO - PROCESS | 10631 | Values: 760.6 809.3 840.1 892.0 923.0 31679.7 43543.0 44317.8 44398.6 44443.3 44839.6 44933.0 44936.6 45130.0 45161.8 7744.8 7921.6 9139.7 9267.3 9267.7 10356.0 10639.6 10640.6 10644.9 10952.1 10678.3 10687.4 10726.6 10728.3 10736.3 10615.7 10661.2 10704.4 10704.5 10718.5 10628.2 10648.8 10649.0 10697.3 10697.9 41534.4 41627.6 41764.8 41797.6 41854.0 4197.8 4201.3 4234.4 4324.7 4350.6 29745.6 30238.1 30263.4 30682.7 31570.5 3959.7 4146.4 4223.0 4249.7 4307.0 248835.0 249434.0 249632.0 249817.0 249824.0 54890.7 55141.4 55165.7 55185.4 55219.0 57609.4 57674.7 57857.0 57932.0 58098.4 16246.8 16656.6 16662.2 16662.5 16663.8 11846.2 11929.6 13401.8 14493.2 14495.1 12988.0 13012.7 13071.5 13190.9 13231.9 5413.2 5497.3 5500.3 5505.4 5522.1 990.5 994.2 996.9 997.8 1018.3 26132.6 26227.0 26232.5 26413.2 26612.2 1064.2 1083.1 1092.0 1105.4 1113.1 14277.7 14466.3 14535.3 14766.3 14782.6 338111.0 340584.0 340781.0 341367.0 341776.0 57403.0 57469.7 57520.4 57522.0 57561.4 1508.1 1513.9 1552.9 1555.7 1564.8 24085.6 27626.7 28304.9 28720.0 30476.2 46580.8 47034.8 47122.1 47130.8 47154.8 365587.0 367116.0 368653.0 369019.0 369921.0 29744.6 29833.6 30082.8 30617.7 33113.7 40315.6 40362.8 40411.4 40584.6 40914.8 30973.0 31135.1 31425.8 31780.4 32021.4 31403.4 31497.2 31548.8 31670.4 32156.2 35164.3 35491.0 35909.6 35979.3 36171.5 29706.0 29990.4 30299.3 30359.4 30413.4 23378.3 23920.6 24390.0 24468.3 24691.0 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - PROCESS | 10631 | [#2] jquery.html Cycles:180 Average:38552.79 Median:13413.49 stddev:115547.15 (861.4%) stddev-sans-first:115835.81 05:25:00 INFO - PROCESS | 10631 | Values: 420.6 465.4 469.9 470.2 485.5 19110.8 19866.3 20945.6 21163.9 21456.9 21015.7 21447.8 21601.7 21980.5 21987.3 6619.5 6757.5 6802.5 6855.0 6929.3 4146.5 4368.3 6732.0 6842.6 6847.2 7333.3 7577.9 7588.3 7614.4 7689.3 7484.6 7534.7 7543.4 7548.4 7645.7 7246.8 7365.4 7368.8 7482.4 7546.2 24422.3 24733.0 25088.0 25292.1 25361.9 3119.6 3274.8 3281.5 3330.0 3366.9 15652.7 16155.7 16429.3 16438.7 16935.8 2291.2 2333.6 2365.3 2404.7 2499.8 116641.0 120162.0 120665.0 121306.0 121962.0 14542.2 16486.4 18208.9 18298.8 18691.6 31830.2 32047.8 32108.5 32141.8 32608.5 9809.1 9809.1 10001.9 10012.7 10065.2 8303.5 8322.8 8362.2 8500.2 8509.4 9121.0 9186.2 9401.8 9487.1 9629.4 3648.9 3709.6 3944.7 3975.9 4039.1 958.5 972.9 979.1 988.7 988.7 20079.9 20232.4 20361.3 20527.8 20598.8 982.7 989.2 991.5 992.5 1003.2 10906.1 11265.0 11324.8 11328.7 11363.0 672758.0 678344.0 706514.0 724092.0 728648.0 88411.0 93585.5 108441.0 116181.4 120717.0 1265.4 1321.4 1331.8 1338.3 1372.8 12910.6 16588.3 18970.8 19120.5 19126.5 49359.5 49425.0 51305.0 51865.7 53501.5 63389.0 63532.0 63846.4 64107.4 66188.0 10479.9 11555.9 11999.5 12452.6 12527.5 14081.4 14127.4 14143.6 14162.6 14188.3 12254.0 12280.5 12366.9 12440.1 12656.1 11230.6 11404.0 11432.9 11568.9 11654.6 12017.8 12307.3 12396.5 12456.7 12878.2 12191.6 12281.4 12415.5 12487.4 12647.7 12228.1 12343.6 12409.3 12676.8 12688.6 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - PROCESS | 10631 | [#3] mootools.html Cycles:180 Average:7102.57 Median:2377.48 stddev:4546.43 (191.2%) stddev-sans-first:4539.83 05:25:00 INFO - PROCESS | 10631 | Values: 1518.9 1525.3 1543.3 1549.4 1551.2 10834.2 11492.5 11507.6 11525.2 11529.3 11242.2 11362.9 11652.1 11667.6 11684.5 2329.6 2388.2 2399.7 2401.0 2401.5 2337.8 2419.5 2423.0 2423.3 2425.8 2336.5 2367.3 2387.7 2407.4 2411.7 2275.3 2331.5 2335.2 2343.3 2345.4 2268.4 2282.9 2287.0 2289.8 2291.3 11145.0 11388.1 11388.5 11398.7 11399.3 5593.4 5717.0 5723.9 5724.3 5729.8 3324.2 3398.2 3466.5 3494.2 3528.5 1235.3 1272.2 1279.4 1280.5 1342.7 14955.1 15281.0 16422.6 17038.7 17536.8 4797.4 4802.2 4889.3 4892.1 4903.9 14330.2 14445.6 14450.8 14453.8 14454.5 5000.7 5148.2 5646.5 5728.1 5794.3 5269.3 5292.7 5372.1 5454.2 5459.2 4276.6 4279.5 4295.8 4333.5 4340.2 1128.4 1159.1 1171.6 1177.2 1181.0 850.5 866.3 873.0 877.2 898.1 7142.9 7229.0 7230.3 7246.0 7328.5 1021.0 1048.5 1051.7 1063.4 1076.1 4573.2 4575.8 4635.9 4672.8 4726.2 13337.4 13782.1 14368.5 14390.6 14395.4 14095.7 14108.3 14116.8 14129.4 14141.2 10283.5 10464.2 10466.3 10492.3 10506.8 6613.3 6744.7 6771.6 6835.7 6912.2 12856.9 12978.2 13087.2 13168.7 13205.1 13246.4 13264.1 13309.1 13354.5 13394.1 9899.6 9922.9 9933.2 10040.2 10062.1 10744.1 10751.3 10832.0 10852.6 10857.5 9274.5 9315.7 9402.5 9497.6 9547.1 9033.5 9173.6 9237.1 9255.6 9315.3 8187.5 8269.0 8326.9 8329.4 8338.3 6138.2 6260.7 6271.1 6290.2 6397.6 6140.5 6318.6 6329.9 6468.3 6553.8 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - PROCESS | 10631 | [#4] prototype.html Cycles:180 Average:4860.53 Median:5365.30 stddev:1270.32 (23.7%) stddev-sans-first:1229.48 05:25:00 INFO - PROCESS | 10631 | Values: 424.6 424.8 442.6 446.1 446.5 5335.7 5352.9 5356.3 5377.3 5384.8 5109.8 5225.8 5236.3 5259.9 5340.3 4464.7 4471.5 4477.8 4482.2 4492.9 4490.6 4524.5 4532.8 4532.8 4536.6 4230.6 4468.4 4491.8 4492.5 4517.9 4444.0 4449.8 4458.4 4476.5 4491.5 4204.7 4208.6 4231.8 4246.4 4278.5 5063.7 5079.7 5124.8 5179.5 5222.2 5152.7 5177.4 5187.8 5190.6 5195.3 5532.8 5556.9 5557.6 5574.8 5598.4 4390.6 4579.2 4595.2 4597.5 4676.2 5493.9 5569.6 5630.5 5737.8 5778.2 4658.9 4714.0 4714.6 4715.6 4755.7 4600.7 4676.6 4817.6 4824.0 4836.3 5006.6 5101.5 5105.7 5123.3 5153.5 5528.6 5528.8 5530.9 5545.1 5561.2 1663.6 1667.7 1714.8 1737.5 1771.4 1423.7 1445.8 1470.9 1479.4 1493.3 5387.4 5416.2 5422.5 5434.0 5448.4 5685.0 5714.8 5715.3 5716.3 5720.8 5161.8 5314.7 5415.4 5423.0 5448.5 2990.4 3004.0 3004.8 3013.9 3038.0 5744.6 5907.8 5917.8 5948.0 5971.3 5799.8 5824.6 5826.7 5838.7 5861.2 5893.5 5904.4 5976.9 5988.5 5988.6 5451.5 5652.7 5685.1 5705.5 5720.0 5374.3 5491.8 5501.4 5630.1 5678.9 5974.4 5975.3 5979.3 5984.5 5994.4 4522.5 4716.2 4720.9 4730.5 4751.2 5593.3 5652.5 5699.9 5729.7 5739.8 5601.8 5603.6 5609.0 5614.6 5615.4 5392.3 5581.0 5591.1 5611.4 5628.6 5548.2 5550.6 5567.4 5577.3 5584.6 5549.1 5559.9 5571.6 5575.3 5578.6 5288.7 5442.0 5476.3 5478.5 5503.2 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - PROCESS | 10631 | [#5] yui.html Cycles:180 Average:18419.17 Median:44323.95 stddev:23710.60 (53.5%) stddev-sans-first:23740.38 05:25:00 INFO - PROCESS | 10631 | Values: 843.0 844.7 873.8 883.3 890.8 16013.3 16926.4 17087.6 17092.7 17213.9 16484.7 16648.9 16660.8 16697.7 16722.2 888.1 890.6 892.3 898.0 904.1 898.8 904.6 904.7 906.9 908.6 958.5 960.1 961.1 961.2 961.6 944.1 944.1 944.6 944.7 945.5 702.8 703.1 706.2 706.6 706.9 16815.6 17058.5 17234.4 17298.0 17308.1 15124.5 15195.8 15339.2 15451.3 15707.5 12856.0 13147.9 13286.5 13327.5 13502.3 3774.2 3855.8 3859.9 3878.9 3962.3 67080.7 98005.5 98223.5 98430.5 98506.0 12269.4 12512.0 12554.0 12562.8 12839.4 20751.7 20904.8 20952.8 21154.6 21164.8 8640.1 8679.3 8811.4 8819.8 8871.0 4916.7 5005.4 5031.5 5045.2 5077.3 3623.9 3724.7 3725.9 3727.5 3739.8 613.4 632.9 637.9 639.2 639.7 856.7 865.3 873.8 882.8 901.6 10768.5 11626.8 11630.8 11768.9 12257.8 822.5 824.1 827.5 842.4 846.3 5096.1 5100.3 5102.5 5114.3 5150.9 78977.0 80499.0 80502.6 81597.6 81786.0 69818.7 70049.6 71429.0 73106.6 74180.6 62428.0 63104.4 63342.0 63726.7 63755.4 9780.3 10029.5 10053.9 10059.6 10065.2 43900.6 44096.3 44551.6 44828.6 45668.8 49931.0 50351.0 50379.0 51444.2 52282.5 11998.3 12386.8 12426.6 12468.4 12553.8 13144.9 13153.1 13209.4 13282.7 13291.0 11526.4 11560.6 11635.7 11685.6 13286.2 15464.7 15603.5 16038.7 16040.9 16214.3 13876.6 13940.0 14098.6 14257.8 14258.0 10764.6 10780.3 10859.2 11160.3 11354.2 12750.3 12847.2 12852.2 13158.5 13205.6 05:25:00 INFO - PROCESS | 10631 | -------- Summary: end -------- 05:25:00 INFO - PROCESS | 10631 | 05:25:00 INFO - TEST-INFO | 10631: exit 0 05:25:00 INFO - TEST-OK | dromaeo_css | took 1115287ms 05:25:00 INFO - TEST-START | kraken 05:25:00 INFO - operating with platform_type : linux_ 05:25:00 INFO - Initialising browser for kraken test... 05:25:00 INFO - TEST-INFO | started process 10955 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuDtG1w/profile http://localhost:54342/getInfo.html) 05:25:05 INFO - PROCESS | 10955 | __metrics Screen width/height:1600/1200 05:25:05 INFO - PROCESS | 10955 | colorDepth:24 05:25:05 INFO - PROCESS | 10955 | Browser inner width/height: 1024/697 05:25:05 INFO - PROCESS | 10955 | __metrics 05:25:05 INFO - PROCESS | 10955 | 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" 05:25:05 INFO - PROCESS | 10955 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:06 INFO - TEST-INFO | 10955: exit 0 05:25:06 INFO - Browser initialized. 05:25:06 INFO - Running cycle 1/1 for kraken test... 05:25:06 INFO - TEST-INFO | started process 11042 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuDtG1w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:18 INFO - PROCESS | 11042 | RSS: Main: 140058624 05:25:18 INFO - PROCESS | 11042 | 05:25:21 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54342/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:22 INFO - PROCESS | 11042 | RSS: Main: 184451072 05:25:22 INFO - PROCESS | 11042 | 05:25:24 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54342/tests/kraken/driver.html?testName=audio-dft) 05:25:25 INFO - PROCESS | 11042 | RSS: Main: 174845952 05:25:25 INFO - PROCESS | 11042 | 05:25:28 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54342/tests/kraken/driver.html?testName=audio-fft) 05:25:28 INFO - PROCESS | 11042 | RSS: Main: 266375168 05:25:28 INFO - PROCESS | 11042 | 05:25:30 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54342/tests/kraken/driver.html?testName=audio-oscillator) 05:25:31 INFO - PROCESS | 11042 | RSS: Main: 171851776 05:25:31 INFO - PROCESS | 11042 | 05:25:33 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54342/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:34 INFO - PROCESS | 11042 | RSS: Main: 174325760 05:25:34 INFO - PROCESS | 11042 | 05:25:36 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54342/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:37 INFO - PROCESS | 11042 | RSS: Main: 213385216 05:25:37 INFO - PROCESS | 11042 | 05:25:41 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54342/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:41 INFO - PROCESS | 11042 | RSS: Main: 212705280 05:25:41 INFO - PROCESS | 11042 | 05:25:44 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54342/tests/kraken/driver.html?testName=json-parse-financial) 05:25:44 INFO - PROCESS | 11042 | RSS: Main: 210747392 05:25:44 INFO - PROCESS | 11042 | 05:25:46 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54342/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:47 INFO - PROCESS | 11042 | RSS: Main: 173232128 05:25:47 INFO - PROCESS | 11042 | 05:25:48 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54342/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:49 INFO - PROCESS | 11042 | RSS: Main: 180367360 05:25:49 INFO - PROCESS | 11042 | 05:25:51 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54342/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:51 INFO - PROCESS | 11042 | RSS: Main: 189661184 05:25:51 INFO - PROCESS | 11042 | 05:25:54 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54342/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:25:55 INFO - PROCESS | 11042 | RSS: Main: 197681152 05:25:55 INFO - PROCESS | 11042 | 05:25:57 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54342/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:58 INFO - PROCESS | 11042 | RSS: Main: 195014656 05:25:58 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | Cycle 1(1): loaded http://localhost:54342/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54342/tests/kraken/driver.html?testName=ai-astar) 05:26:00 INFO - PROCESS | 11042 | RSS: Main: 245506048 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | __start_tp_report 05:26:00 INFO - PROCESS | 11042 | _x_x_mozilla_page_load 05:26:00 INFO - PROCESS | 11042 | _x_x_mozilla_page_load_details 05:26:00 INFO - PROCESS | 11042 | |i|pagename|runs| 05:26:00 INFO - PROCESS | 11042 | |0;ai-astar;103;113;112;111;113;112;111;111;113;111 05:26:00 INFO - PROCESS | 11042 | |1;audio-beat-detection;147;120;121;154;114;118;115;115;136;120 05:26:00 INFO - PROCESS | 11042 | |2;audio-dft;160;161;160;160;161;160;160;159;159;160 05:26:00 INFO - PROCESS | 11042 | |3;audio-fft;74;75;75;113;75;76;75;75;93;74 05:26:00 INFO - PROCESS | 11042 | |4;audio-oscillator;86;81;102;81;82;82;97;81;81;109 05:26:00 INFO - PROCESS | 11042 | |5;imaging-gaussian-blur;113;114;113;114;113;114;113;114;113;112 05:26:00 INFO - PROCESS | 11042 | |6;imaging-darkroom;147;147;146;146;148;148;147;147;147;164 05:26:00 INFO - PROCESS | 11042 | |7;imaging-desaturate;95;95;94;95;93;94;94;94;94;93 05:26:00 INFO - PROCESS | 11042 | |8;json-parse-financial;54;53;53;54;54;54;57;54;54;54 05:26:00 INFO - PROCESS | 11042 | |9;json-stringify-tinderbox;52;52;53;52;52;52;52;52;51;52 05:26:00 INFO - PROCESS | 11042 | |10;stanford-crypto-aes;86;76;76;81;76;88;89;79;79;78 05:26:00 INFO - PROCESS | 11042 | |11;stanford-crypto-ccm;123;122;119;120;120;126;119;127;162;119 05:26:00 INFO - PROCESS | 11042 | |12;stanford-crypto-pbkdf2;162;164;165;164;159;166;163;163;157;160 05:26:00 INFO - PROCESS | 11042 | |13;stanford-crypto-sha256-iterative;61;59;64;64;65;60;61;62;58;59 05:26:00 INFO - PROCESS | 11042 | __end_tp_report 05:26:00 INFO - PROCESS | 11042 | __start_cc_report 05:26:00 INFO - PROCESS | 11042 | _x_x_mozilla_cycle_collect,1664 05:26:00 INFO - PROCESS | 11042 | __end_cc_report 05:26:00 INFO - PROCESS | 11042 | __startTimestamp1491481560301__endTimestamp 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | ------- Summary: start ------- 05:26:00 INFO - PROCESS | 11042 | Number of tests: 14 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:2.94 (2.6%) stddev-sans-first:0.93 05:26:00 INFO - PROCESS | 11042 | Values: 103.0 113.0 112.0 111.0 113.0 112.0 111.0 111.0 113.0 111.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:120.50 stddev:14.42 (12.0%) stddev-sans-first:13.14 05:26:00 INFO - PROCESS | 11042 | Values: 147.0 120.0 121.0 154.0 114.0 118.0 115.0 115.0 136.0 120.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 05:26:00 INFO - PROCESS | 11042 | Values: 160.0 161.0 160.0 160.0 161.0 160.0 160.0 159.0 159.0 160.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.00 stddev:12.77 (17.0%) stddev-sans-first:13.33 05:26:00 INFO - PROCESS | 11042 | Values: 74.0 75.0 75.0 113.0 75.0 76.0 75.0 75.0 93.0 74.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:81.50 stddev:10.49 (12.9%) stddev-sans-first:11.09 05:26:00 INFO - PROCESS | 11042 | Values: 86.0 81.0 102.0 81.0 82.0 82.0 97.0 81.0 81.0 109.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:26:00 INFO - PROCESS | 11042 | Values: 113.0 114.0 113.0 114.0 113.0 114.0 113.0 114.0 113.0 112.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:147.00 stddev:5.42 (3.7%) stddev-sans-first:5.71 05:26:00 INFO - PROCESS | 11042 | Values: 147.0 147.0 146.0 146.0 148.0 148.0 147.0 147.0 147.0 164.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 05:26:00 INFO - PROCESS | 11042 | Values: 95.0 95.0 94.0 95.0 93.0 94.0 94.0 94.0 94.0 93.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:1.10 (2.0%) stddev-sans-first:1.17 05:26:00 INFO - PROCESS | 11042 | Values: 54.0 53.0 53.0 54.0 54.0 54.0 57.0 54.0 54.0 54.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#9] json-stringify-tinderbox Cycles:10 Average:52.00 Median:52.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 05:26:00 INFO - PROCESS | 11042 | Values: 52.0 52.0 53.0 52.0 52.0 52.0 52.0 52.0 51.0 52.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:80.00 stddev:5.05 (6.3%) stddev-sans-first:4.99 05:26:00 INFO - PROCESS | 11042 | Values: 86.0 76.0 76.0 81.0 76.0 88.0 89.0 79.0 79.0 78.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:122.50 stddev:13.08 (10.7%) stddev-sans-first:13.84 05:26:00 INFO - PROCESS | 11042 | Values: 123.0 122.0 119.0 120.0 120.0 126.0 119.0 127.0 162.0 119.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:163.50 stddev:2.83 (1.7%) stddev-sans-first:3.00 05:26:00 INFO - PROCESS | 11042 | Values: 162.0 164.0 165.0 164.0 159.0 166.0 163.0 163.0 157.0 160.0 05:26:00 INFO - PROCESS | 11042 | 05:26:00 INFO - PROCESS | 11042 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.50 stddev:2.41 (3.9%) stddev-sans-first:2.55 05:26:00 INFO - PROCESS | 11042 | Values: 61.0 59.0 64.0 64.0 65.0 60.0 61.0 62.0 58.0 59.0 05:26:00 INFO - PROCESS | 11042 | -------- Summary: end -------- 05:26:00 INFO - PROCESS | 11042 | 05:26:01 INFO - TEST-INFO | 11042: exit 0 05:26:01 INFO - TEST-OK | kraken | took 60456ms 05:26:01 INFO - SUITE-END | took 1175s 05:26:01 INFO - Completed test suite (00:19:35) 05:26:01 INFO - javascript benchmark 05:26:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7810.962001290281, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3361.394948909197, 3385.1198688341033, 3386.661333866612, 3398.0, 3442.845071971761, 5604.915926261105, 5667.461591148839, 5672.0, 5692.37383887773, 5694.487496125353, 5693.715314234282, 5728.140778883169, 5748.798792042279, 5754.280714910637, 5794.188813566104, 5178.792848286064, 5187.377514698242, 5190.506901844319, 5192.61055420845, 5239.240310155025, 5102.69383836971, 5112.182050871861, 5279.815206467774, 5282.366116066078, 5309.0709125903, 5153.845384638448, 5156.484351564851, 5228.032813929416, 5235.0, 5245.79016839326, 5232.241325007872, 5264.657797243186, 5271.683698978035, 5273.2617433559335, 5300.0, 5034.068697290976, 5120.743962801882, 5187.610929180327, 5236.371635403738, 5237.214417837316, 5552.08390615548, 5594.468525490072, 5636.098224284095, 5642.238297829823, 5647.717614119319, 6059.39406059395, 6096.4817990470865, 6109.5723299369065, 6118.4799292060225, 6119.26568811741, 6119.87760244797, 6136.539759518054, 6141.078838174265, 6143.846403839895, 6144.385561443864, 5555.527780138693, 5585.413531579166, 5674.773009079632, 5704.914426283609, 5715.571332150105, 6242.563020588515, 6248.937510624835, 6249.437550620466, 6254.15568898202, 6265.906011409833, 5962.880742385128, 5964.224650795456, 6020.548458865515, 6021.969890150521, 6070.028795392805, 5869.911951320733, 5904.232449781587, 6019.0068638675, 6078.817635470943, 6086.360932102066, 6006.759729610854, 6053.213082299273, 6057.3336932937345, 6073.605215660968, 6090.878182436327, 5876.294844618673, 5913.674747888907, 5996.070609055516, 6010.66941318223, 6061.484773794277, 4618.053299073749, 4666.859994200179, 4683.742394168354, 4685.226937555265, 4691.319758634979, 4067.7762723049923, 4071.2875246831686, 4158.106007208404, 4168.624823765815, 4204.222218889515, 5715.628484148517, 5835.44563266489, 5909.8227053188475, 5949.077892926603, 5964.7315870785915, 6170.352113028068, 6175.69121543921, 6176.073588961692, 6179.62922224668, 6180.381961803783, 5706.714664266853, 5923.851903702442, 5932.7330270137945, 5933.050711886078, 5933.6736479493175, 5155.7679904404395, 5158.613104017214, 5200.167973124358, 5240.4497527759795, 5247.685138891678, 6381.4575761059905, 6405.551611387158, 6410.679466026773, 6422.518311126684, 6424.3254458282145, 6058.0, 6117.0, 6121.969390153199, 6132.693365331805, 6134.907976380268, 6134.252905685562, 6169.660668663267, 6327.841803954938, 6337.397947195009, 6444.162258906312, 6046.969765151146, 6050.364711705208, 6052.152698622109, 6062.605930614496, 6093.664848433379, 6151.477124444473, 6159.076138579249, 6164.660943648142, 6165.568410211242, 6166.414190651881, 5681.687507187145, 5794.82615521535, 5908.822735317947, 5965.522758179249, 6005.129256257906, 5997.250343707036, 6000.51995840323, 6001.609895356788, 6021.187139736079, 6030.698465076816, 5902.291724993029, 5947.732352044255, 5953.1070339449425, 5969.582129250911, 5972.223610930725, 5774.855628609151, 5806.5064469518875, 5835.037218859011, 5840.8247752567495, 5843.2695913010875, 5799.565032622654, 5856.385079566755, 5915.349311575809, 5915.467607915309, 5922.674252915958], "value": 5628.805664374738, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.6272926266128, 809.2676128104066, 840.0717207485728, 892.023234558159, 922.981540369193, 31679.683203167962, 43543.0, 44317.7784111079, 44398.556014439884, 44443.333349999775, 44839.551604483946, 44933.0, 44936.550634493644, 45130.0, 45161.774191129, 7744.767656970272, 7921.56431396273, 9139.680111196127, 9267.258619310473, 9267.67563135287, 10356.0, 10639.627613033545, 10640.574377024948, 10644.946775266113, 10952.069074128669, 10678.305910115856, 10687.358758474478, 10726.624568140116, 10728.302660327094, 10736.30214036085, 10615.681529554126, 10661.200409969244, 10704.357738535635, 10704.51829667663, 10718.464076796128, 10628.202884783672, 10648.78702425947, 10648.988346107108, 10697.304675196086, 10697.893021069845, 41534.37698434526, 41627.58372416267, 41764.79117604423, 41797.582024179675, 41854.0, 4197.790110494463, 4201.306784380574, 4234.449521562177, 4324.6972711910175, 4350.630196433307, 29745.553816692765, 30238.09285721432, 30263.394732105455, 30682.693173068208, 31570.526442103386, 3959.742616729903, 4146.3780432935, 4222.9577704222875, 4249.745015299092, 4307.030918043447, 248835.0, 249434.0, 249632.0, 249817.0, 249823.9999999982, 54890.725546372814, 55141.44858551443, 55165.72417137888, 55185.44814551803, 55219.0, 57609.423905760406, 57674.711626441596, 57857.0, 57932.0, 58098.41901581015, 16246.837531624533, 16656.58358541022, 16662.166891655368, 16662.50012499627, 16663.833361666228, 11846.170768046153, 11929.642110736691, 13401.798973015413, 14493.20287384204, 14495.130292182503, 12987.960963122927, 13012.674683132807, 13071.477140914447, 13190.934045329905, 13231.933840330737, 5413.188021796762, 5497.312835895513, 5500.257465242141, 5505.421930697299, 5522.088855338904, 990.4503000834462, 994.2046362909643, 996.8803743550675, 997.7555498902815, 1018.277023313441, 26132.608010879852, 26227.0, 26232.475350492885, 26413.207603771916, 26612.201633951012, 1064.1858977881927, 1083.0523292119397, 1092.0335503079832, 1105.4307031878589, 1113.0705860606322, 14277.714445711235, 14466.349014294172, 14535.27323633814, 14766.261686915612, 14782.63043423923, 338111.0, 340584.0, 340781.0, 341367.0, 341776.0, 57403.0, 57469.71265143731, 57520.4247957515, 57522.0, 57561.42438575644, 1508.0951429142426, 1513.8940274180702, 1552.8524790144918, 1555.6655319106446, 1564.8278689344163, 24085.638715419635, 27626.723732762413, 28304.931480843046, 28720.0, 30476.238094046937, 46580.7670961643, 47034.76482617565, 47122.057558849316, 47130.764346178046, 47154.764226178646, 365587.0, 367116.0, 368653.0, 369019.0, 369921.0, 29744.55383169211, 29833.55249671213, 30082.84958575193, 30617.693823062375, 33113.66886331106, 40315.596844031184, 40362.798186010055, 40411.393829092, 40584.59415405808, 40914.795426022676, 30973.0, 31135.13189208655, 31425.8428707855, 31780.364392711555, 32021.35957280888, 31403.371932561677, 31497.21256968594, 31548.842255788575, 31670.366592668477, 32156.19609509781, 35164.296714066084, 35491.0, 35909.64090359063, 35979.28041439104, 36171.457428139125, 29706.0, 29990.400191996476, 30299.333536655697, 30359.392812144077, 30413.391732165674, 23378.298651040495, 23920.64119038181, 24390.0, 24468.26595202147, 24691.0], "value": 18955.539544588668, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.5668161793354, 465.39266257533944, 469.91684167992764, 470.1631096647966, 485.4538644025471, 19110.808891911078, 19866.30467933619, 20945.581088378243, 21163.894180529096, 21456.8927155364, 21015.684764728503, 21447.785522144775, 21601.67597486035, 21980.450488737788, 21987.34037978859, 6619.536632435731, 6757.493188010906, 6802.455803535705, 6855.0, 6929.341712537301, 4146.481689788777, 4368.301071828508, 6731.966340168292, 6842.55523390978, 6847.212570554375, 7333.339999400053, 7577.886331705029, 7588.27911348421, 7614.390848732116, 7689.307962283393, 7484.550926944374, 7534.660940257701, 7543.39652827778, 7548.396128309777, 7645.706288113543, 7246.782596522113, 7365.373943214833, 7368.815779605499, 7482.401407887356, 7546.207648196915, 24422.26733198007, 24733.0, 25088.0, 25292.114775982936, 25361.873190634113, 3119.5788568543185, 3274.836258187081, 3281.5241789940324, 3329.9843547717937, 3366.8653253869816, 15652.68694626101, 16155.676886462323, 16429.342826286935, 16438.671226575403, 16935.83064169355, 2291.163725240292, 2333.626619740833, 2365.3258821236036, 2404.711434627856, 2499.800015998734, 116641.0, 120162.0, 120665.0, 121306.0, 121962.0, 14542.21341916316, 16486.422975196052, 18208.90448623737, 18298.817011829713, 18691.626167476847, 31830.204244894063, 32047.839760801515, 32108.518372224436, 32141.839290803862, 32608.510872336934, 9809.117179453884, 9809.117179453884, 10001.899981000242, 10012.69961901144, 10065.194784417228, 8303.543305118277, 8322.750317490485, 8362.163783621589, 8500.192481714228, 8509.404341696021, 9121.0, 9186.173244408035, 9401.764955876159, 9487.051294870458, 9629.422234665943, 3648.945265821015, 3709.6104908984707, 3944.692354972316, 3975.9463742108364, 4039.1315867088715, 958.4584709639032, 972.8686627305365, 979.0796651147898, 988.6737376665683, 988.7083310423442, 20079.899600502195, 20232.39302820918, 20361.287354942506, 20527.794722052888, 20598.794012059687, 982.6953644370268, 989.2333441582831, 991.5191132300828, 992.5285489392625, 1003.1924300937815, 10906.072983796308, 11265.0, 11324.773504529863, 11328.660140195807, 11362.977332040156, 672758.0, 678344.0, 706514.0, 724092.0, 728648.0, 88411.0, 93585.5320723392, 108441.0, 116181.41909290568, 120717.0, 1265.4242319744494, 1321.3813078978367, 1331.7536255792515, 1338.3174580963778, 1372.84212315583, 12910.612681619567, 16588.336466541205, 18970.810291896905, 19120.521986950436, 19126.52183695363, 49359.50640493549, 49425.011499770524, 51305.0, 51865.7406712964, 53501.4649853512, 63389.0, 63532.04701929382, 63846.36153638404, 64107.35892641014, 66188.0, 10479.895201047892, 11555.942220288845, 11999.460024299102, 12452.626421207378, 12527.498900043896, 14081.366338514586, 14127.43490260378, 14143.575692729235, 14162.645933851323, 14188.290585470479, 12254.0, 12280.508779648713, 12366.876331236572, 12440.066994975232, 12656.11407201487, 11230.55077796879, 11404.0, 11432.885671143182, 11568.88431115678, 11654.592089276835, 12017.75964480688, 12307.323097229739, 12396.504139834666, 12456.688582785502, 12878.162919410208, 12191.573294934635, 12281.44733487013, 12415.503379864704, 12487.381836806804, 12647.747045059232, 12228.144029917821, 12343.629691109281, 12409.317487538272, 12676.809847852106, 12688.555900543437], "value": 11430.976393757852, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1518.8784897208225, 1525.344102036124, 1543.3055125193662, 1549.3570168380122, 1551.2243878060963, 10834.241603087787, 11492.482838272277, 11507.597234096818, 11525.19323647345, 11529.308241505516, 11242.156838237124, 11362.943185284063, 11652.126090543201, 11667.649970500877, 11684.532618695262, 2329.557384097016, 2388.235764555343, 2399.748026457223, 2400.975990240093, 2401.4836810085826, 2337.801286890616, 2419.4798118404537, 2423.0, 2423.321469988406, 2425.7938075263537, 2336.485973085927, 2367.266147494274, 2387.689600351952, 2407.3500154958238, 2411.7347091819884, 2275.2946586558055, 2331.5453486570127, 2335.1710142899196, 2343.250159948817, 2345.3901985483812, 2268.3648578398074, 2282.8744419056943, 2287.0165828693657, 2289.828262880274, 2291.3126062181445, 11145.0, 11388.088952883749, 11388.54445822166, 11398.658040258806, 11399.316041037564, 5593.356763972114, 5716.971415142898, 5723.885522289573, 5724.2558467399385, 5729.799457018984, 3324.2354258520677, 3398.150462384404, 3466.532018177539, 3494.248736521635, 3528.4530897710897, 1235.2773627427966, 1272.1540175783093, 1279.4306533592555, 1280.532605598959, 1342.7113170668354, 14955.102693838404, 15281.0, 16422.589435263973, 17038.74441883373, 17536.824631753647, 4797.352357431771, 4802.159622066124, 4889.266610008527, 4892.143874821892, 4903.8528844134735, 14330.21183834899, 14445.566633001026, 14450.783238251435, 14453.78319325211, 14454.494092706705, 5000.6999580024585, 5148.150555158431, 5646.463585959327, 5728.083506639001, 5794.333651630032, 5269.314989051399, 5292.655977361459, 5372.059889519397, 5454.181872719124, 5459.208414779835, 4276.593723596254, 4279.529251782302, 4295.78521073945, 4333.479982402132, 4340.218760623118, 1128.4132251229314, 1159.084323384517, 1171.619223752284, 1177.205386364218, 1180.976380472403, 850.4897061762892, 866.2853146154448, 872.9607167677469, 877.1929824561419, 898.1377877237794, 7142.892856607155, 7229.0, 7230.349268565854, 7246.0, 7328.487005909536, 1021.0084452554088, 1048.4967215736337, 1051.6582110813863, 1063.3672964586058, 1076.05844885725, 4573.176828170961, 4575.771211439414, 4635.884102897455, 4672.813087476531, 4726.196546587088, 13337.399817008063, 13782.104163229358, 14368.497102601568, 14390.568282951319, 14395.352209150613, 14095.71808563823, 14108.294585270696, 14116.788248176286, 14129.364178611988, 14141.151530908179, 10283.53724082418, 10464.162866970777, 10466.267361284788, 10492.317999330018, 10506.842397364046, 6613.305602911625, 6744.696488657924, 6771.559848609824, 6835.658217089225, 6912.205096413775, 12856.871431285568, 12978.156419832681, 13087.214767114003, 13168.73662526725, 13205.075644704779, 13246.403911824184, 13264.071514994244, 13309.134906231064, 13354.465821367037, 13394.062415630813, 9899.554520046471, 9922.900770992197, 9933.155681766842, 10040.196784257389, 10062.094411502707, 10744.086752626114, 10751.301165424222, 10832.0, 10852.565897364017, 10857.511411986638, 9274.489903055397, 9315.673951411669, 9402.482863442576, 9497.572609232462, 9547.093026162504, 9033.548322583712, 9173.587188576663, 9237.12247336502, 9255.583498742439, 9315.34792564514, 8187.4678145920325, 8268.958655206927, 8326.875096873673, 8329.416940814328, 8338.332933365438, 6138.1713468681155, 6260.718267678057, 6271.090691849748, 6290.24517057956, 6397.584157029778, 6140.473017325276, 6318.557700961072, 6329.8734025320155, 6468.2561505427475, 6553.836154095995], "value": 5346.698905856483, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.6284501061571, 424.7769920791586, 442.6016585073435, 446.1055583554972, 446.4508654355143, 5335.733213339332, 5352.892942141155, 5356.3036805215315, 5377.327834020747, 5384.846061575372, 5109.824740309722, 5225.764840582174, 5236.2931004314405, 5259.894802103965, 5340.332458442695, 4464.665150113747, 4471.508134105262, 4477.798499067533, 4482.215612267856, 4492.932606010912, 4490.595846373826, 4524.45706515217, 4532.784510405339, 4532.796024178903, 4536.614387777043, 4230.640395566381, 4468.4414448193975, 4491.8096704373365, 4492.483364413101, 4517.932231016521, 4444.044710267668, 4449.844255451044, 4458.3535387368975, 4476.455622810142, 4491.483479399878, 4204.684648651333, 4208.558101399371, 4231.809568569422, 4246.384274280243, 4278.507971583276, 5063.746812659352, 5079.739515915419, 5124.795008199668, 5179.533841954205, 5222.164453687392, 5152.690838549699, 5177.37871455424, 5187.766550505236, 5190.584753219733, 5195.298634684307, 5532.763333449896, 5556.9444305556835, 5557.6109672322955, 5574.832755017356, 5598.44015598441, 4390.604845563883, 4579.175748365293, 4595.195840727859, 4597.517260687613, 4676.2050451423, 5493.917591236134, 5569.554435645138, 5630.451625802896, 5737.770489180428, 5778.219940308089, 4658.86023419298, 4713.9764301178275, 4714.622830173578, 4715.646326525456, 4755.690880092783, 4600.723956562617, 4676.602488788423, 4817.638677099231, 4823.9517604824205, 4836.322914791932, 5006.6245031622775, 5101.464346243591, 5105.744712764347, 5123.333966584395, 5153.484651534816, 5528.612997090246, 5528.838943821762, 5530.889382212334, 5545.057340252167, 5561.249231353715, 1663.6256842210648, 1667.7498375243567, 1714.802797678258, 1737.530866665993, 1771.353455988555, 1423.6583220027119, 1445.7903603977365, 1470.9485168019069, 1479.4452080469823, 1493.27576125579, 5387.437643083462, 5416.158506107942, 5422.48486393792, 5434.0, 5448.400675925645, 5685.0, 5714.771409143587, 5715.256846661725, 5716.28546431696, 5720.8283751487525, 5161.845144645667, 5314.654547454403, 5415.431379705153, 5422.972885135549, 5448.482394172547, 2990.3983534325275, 3004.038707613586, 3004.849757512159, 3013.909582712522, 3037.9544306835414, 5744.569157313134, 5907.793227237026, 5917.751254523254, 5947.970260148758, 5971.28344598651, 5799.82600521985, 5824.592278540462, 5826.650400975955, 5838.7080645967535, 5861.17943487912, 5893.528517718572, 5904.439078287556, 5976.8804623908145, 5988.4909782668965, 5988.580799344091, 5451.536619387396, 5652.738904438258, 5685.147227915681, 5705.515031222227, 5719.971400142973, 5374.291738700284, 5491.764353020539, 5501.367342755474, 5630.0991841305195, 5678.914816277676, 5974.4324289192455, 5975.312839023568, 5979.342272349951, 5984.521238300838, 5994.430529099728, 4522.547745225583, 4716.221823399101, 4720.929186062281, 4730.4796472387325, 4751.239801631722, 5593.328800543961, 5652.463016013472, 5699.919529371832, 5729.713514324184, 5739.770409183586, 5601.80393686219, 5603.62916924783, 5609.0, 5614.550835933197, 5615.354234262949, 5392.272043274108, 5580.967521008626, 5591.058303895662, 5611.354694210055, 5628.605997580294, 5548.195511650707, 5550.55595552363, 5567.415421380859, 5577.295011919135, 5584.636998595079, 5549.116777236007, 5559.916601251065, 5571.554275658019, 5575.303087114111, 5578.63738856973, 5288.65623734456, 5442.047641662614, 5476.315460567429, 5478.506934375766, 5503.156442591617], "value": 4503.251336213751, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [842.9620667069981, 844.6536919862854, 873.7815546113471, 883.3066043156124, 890.8040231149149, 16013.27940242689, 16926.407575734822, 17087.572810679725, 17092.743608845878, 17213.913930430346, 16484.67030659386, 16648.916755416238, 16660.83339166608, 16697.749533757004, 16722.16389180539, 888.1207604471591, 890.5636238243246, 892.3129190523293, 897.9730608081768, 904.0507467159489, 898.8112496375769, 904.5612877754317, 904.7331037343966, 906.9319801014917, 908.5956749246587, 958.4776296918196, 960.0879164793439, 961.1349785193312, 961.2262128986198, 961.6009356117203, 944.0653752784729, 944.0936700767237, 944.5654998700606, 944.7354740672588, 945.546137853834, 702.7821375373651, 703.1281211298004, 706.1737766812842, 706.5795851468368, 706.86569551785, 16815.579610509714, 17058.48824535266, 17234.3967961122, 17298.0, 17308.134593270286, 15124.470643527533, 15195.848041519665, 15339.156346400943, 15451.304691288919, 15707.450239241685, 12856.0, 13147.86852131476, 13286.468541258339, 13327.466901323935, 13502.324883755773, 3774.1647665311625, 3855.8072096395067, 3859.8842034739005, 3878.8836334909997, 3962.306596345628, 67080.66459667719, 98005.50997244968, 98223.50888245585, 98430.50784746102, 98506.0, 12269.386530673431, 12512.0, 12554.0, 12562.81155782664, 12839.358032098358, 20751.68872466914, 20904.79095209059, 20952.790472095083, 21154.57690846205, 21164.78835211659, 8640.092790257058, 8679.262262707614, 8811.400065586171, 8819.823603527893, 8871.024187339388, 4916.704997700078, 5005.374328208974, 5031.496850314939, 5045.2432135180015, 5077.289179514871, 3623.8912832615065, 3724.7020238380865, 3725.925481490355, 3727.4595183698752, 3739.8317075731657, 613.408061220915, 632.9113924050637, 637.8564822914807, 639.1563136659657, 639.6737663791407, 856.7429771068654, 865.251557402842, 873.8121303919561, 882.8455020371538, 901.5987885391069, 10768.461576921123, 11626.825597616042, 11630.76738465226, 11768.88231117695, 12257.754844903053, 822.5270469480073, 824.0729179672868, 827.5241736001702, 842.3598065470287, 846.3398549131687, 5096.133657278272, 5100.285959965608, 5102.540771330598, 5114.28400023997, 5150.896982060338, 78977.0, 80499.0, 80502.59748701219, 81597.59201204075, 81786.0, 69818.65090674514, 70049.64975175091, 71429.0, 73106.63446682732, 74180.62909685525, 62428.0, 63104.36895630985, 63342.0, 63726.68136659287, 63755.36244637587, 9780.266480013885, 10029.54867031, 10053.949730251303, 10059.597616095567, 10065.194784417376, 43900.56099438965, 44096.338554922346, 44551.55448445474, 44828.55171448244, 45668.77165614151, 49931.0, 50351.0, 50379.0, 51444.22833657573, 52282.47717522776, 11998.280103193836, 12386.81419778686, 12426.5650702225, 12468.376581170724, 12553.811692824798, 13144.934275328562, 13153.145045572008, 13209.405576748877, 13282.734345313233, 13291.0, 11526.423678815858, 11560.595379161688, 11635.709107272047, 11685.591004314809, 13286.20282783036, 15464.690706186038, 15603.453879114177, 16038.67922641564, 16040.919795400949, 16214.27035783369, 13876.58370248894, 13940.0, 14098.647533811736, 14257.786133207803, 14258.0, 10764.569417223223, 10780.329048997419, 10859.185561083108, 11160.274582152135, 11354.205205635526, 12750.298733569743, 12847.164934279243, 12852.228866268053, 13158.539451119164, 13205.603831885059], "value": 7732.87922970145, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1458.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 112.0, 111.0, 113.0, 112.0, 111.0, 111.0, 113.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 120.0, 121.0, 154.0, 114.0, 118.0, 115.0, 115.0, 136.0, 120.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 160.0, 159.0, 159.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 113.0, 75.0, 76.0, 75.0, 75.0, 93.0, 74.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 102.0, 81.0, 82.0, 82.0, 97.0, 81.0, 81.0, 109.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 112.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 148.0, 148.0, 147.0, 147.0, 147.0, 164.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 54.0, 54.0, 57.0, 54.0, 54.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 51.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 76.0, 76.0, 81.0, 76.0, 88.0, 89.0, 79.0, 79.0, 78.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 122.0, 119.0, 120.0, 120.0, 126.0, 119.0, 127.0, 162.0, 119.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 165.0, 164.0, 159.0, 166.0, 163.0, 163.0, 157.0, 160.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 64.0, 64.0, 65.0, 60.0, 61.0, 62.0, 58.0, 59.0], "value": 61.3, "unit": "score"}]}]} 05:26:01 INFO - Return code: 0 05:26:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:26:01 ERROR - # TBPL SUCCESS # 05:26:01 INFO - Running post-action listener: _resource_record_post_action 05:26:01 INFO - [mozharness: 2017-04-06 12:26:01.610821Z] Finished run-tests step (success) 05:26:01 INFO - Running post-run listener: _resource_record_post_run 05:26:01 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 188416; Write bytes: 370769920; Read time: 228; Write time: 223416 05:26:01 INFO - TinderboxPrint: CPU usage
13.1% 05:26:01 INFO - TinderboxPrint: I/O read bytes / time
188,416 / 228 05:26:01 INFO - TinderboxPrint: I/O write bytes / time
370,769,920 / 223,416 05:26:01 INFO - TinderboxPrint: CPU idle
8,271.3 (86.9%) 05:26:01 INFO - TinderboxPrint: CPU user
1,201.4 (12.6%) 05:26:01 INFO - TinderboxPrint: Swap in / out
0 / 0 05:26:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 117989376; Read time: 28; Write time: 82956 05:26:01 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 184320; Write bytes: 252780544; Read time: 200; Write time: 140460 05:26:02 INFO - Running post-run listener: _upload_blobber_files 05:26:02 INFO - Blob upload gear active. 05:26:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:02 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 05:26:02 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'] 05:26:02 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 05:26:02 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:26:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:26:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:05 INFO - (blobuploader) - INFO - Done attempting. 05:26:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:06 INFO - (blobuploader) - INFO - Done attempting. 05:26:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:26:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:26:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:07 INFO - (blobuploader) - INFO - Done attempting. 05:26:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:08 INFO - (blobuploader) - INFO - Done attempting. 05:26:08 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:08 INFO - Return code: 0 05:26:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61fa6fc4e3190581595a2def728e8199bec14964e04b27e31841cce17ab114c94da662124a8e2cde7519d77d117a84aebb58cf70e107b3010281f774dfc551c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/207a8216e14e5b1d4dcd9b852fdd3bc43f8b9fa5b7f09dca6f5fc05238de574eb4c6325efe4a0d18e4fc2b9b917d75b852132b9f3a62cbebc619467bd967476c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a062d9caddee74538fef5b3347333bda4a6ff6cac3559d2b1db13f3c39705ee1d86c6ec32ca2ca52a1dafc8a0a890f64a7ceb38b3f5504de3fe032e75d0de2cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c6989e0bdca8a5220176056d39b9bd470f6dfb47506454b7bfe1b334172e75e36b69a4e040ac6e131c8c454f392df17b43291b611b74623fd961d4e0b085c42"} 05:26:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:08 INFO - Contents: 05:26:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61fa6fc4e3190581595a2def728e8199bec14964e04b27e31841cce17ab114c94da662124a8e2cde7519d77d117a84aebb58cf70e107b3010281f774dfc551c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/207a8216e14e5b1d4dcd9b852fdd3bc43f8b9fa5b7f09dca6f5fc05238de574eb4c6325efe4a0d18e4fc2b9b917d75b852132b9f3a62cbebc619467bd967476c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a062d9caddee74538fef5b3347333bda4a6ff6cac3559d2b1db13f3c39705ee1d86c6ec32ca2ca52a1dafc8a0a890f64a7ceb38b3f5504de3fe032e75d0de2cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c6989e0bdca8a5220176056d39b9bd470f6dfb47506454b7bfe1b334172e75e36b69a4e040ac6e131c8c454f392df17b43291b611b74623fd961d4e0b085c42"} 05:26:08 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:08 INFO - Copying logs to upload dir... 05:26:08 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.260471 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-06 05:26:08.121284) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 05:26:08.125140) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865 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/61fa6fc4e3190581595a2def728e8199bec14964e04b27e31841cce17ab114c94da662124a8e2cde7519d77d117a84aebb58cf70e107b3010281f774dfc551c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/207a8216e14e5b1d4dcd9b852fdd3bc43f8b9fa5b7f09dca6f5fc05238de574eb4c6325efe4a0d18e4fc2b9b917d75b852132b9f3a62cbebc619467bd967476c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a062d9caddee74538fef5b3347333bda4a6ff6cac3559d2b1db13f3c39705ee1d86c6ec32ca2ca52a1dafc8a0a890f64a7ceb38b3f5504de3fe032e75d0de2cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c6989e0bdca8a5220176056d39b9bd470f6dfb47506454b7bfe1b334172e75e36b69a4e040ac6e131c8c454f392df17b43291b611b74623fd961d4e0b085c42"} build_url:https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011386 build_url: 'https://queue.taskcluster.net/v1/task/SqgPja7oSxWWkzxwhvJofA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/61fa6fc4e3190581595a2def728e8199bec14964e04b27e31841cce17ab114c94da662124a8e2cde7519d77d117a84aebb58cf70e107b3010281f774dfc551c1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/207a8216e14e5b1d4dcd9b852fdd3bc43f8b9fa5b7f09dca6f5fc05238de574eb4c6325efe4a0d18e4fc2b9b917d75b852132b9f3a62cbebc619467bd967476c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a062d9caddee74538fef5b3347333bda4a6ff6cac3559d2b1db13f3c39705ee1d86c6ec32ca2ca52a1dafc8a0a890f64a7ceb38b3f5504de3fe032e75d0de2cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c6989e0bdca8a5220176056d39b9bd470f6dfb47506454b7bfe1b334172e75e36b69a4e040ac6e131c8c454f392df17b43291b611b74623fd961d4e0b085c42"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 05:26:08.151106) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:26:08.151445) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1491480314.171397-1070396865 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004400 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:26:08.236358) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 05:26:08.237221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 05:26:08.237985) ========= ========= Total master_lag: 0.17 =========