builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-047 starttime: 1492271546.17 results: success (0) revision: 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:26.165475) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:26.165988) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:26.166331) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:26.188448) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:26.188763) ========= 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=03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-15 08:52:26-- 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% 45.9M=0s 2017-04-15 08:52:26 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096837 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:26.299628) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:26.300037) ========= 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=03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022402 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:26.349757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-15 08:52:26.350120) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e --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 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e --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=03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-15 08:52:26,447 truncating revision to first 12 chars 2017-04-15 08:52:26,447 Setting DEBUG logging. 2017-04-15 08:52:26,447 attempt 1/10 2017-04-15 08:52:26,447 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9da0d3c7fc50?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-15 08:52:27,685 unpacking tar archive at: mozilla-aurora-9da0d3c7fc50/testing/mozharness/ program finished with exit code 0 elapsedTime=1.574290 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-15 08:52:27.938807) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:27.939192) ========= script_repo_revision: 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:27.939643) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:27.939952) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 08:52:27.949876) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-15 08:52:27.950204) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:52:28 INFO - MultiFileLogger online at 20170415 08:52:28 in /builds/slave/test 08:52:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 08:52:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:28 INFO - {'append_to_log': False, 08:52:28 INFO - 'base_work_dir': '/builds/slave/test', 08:52:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 08:52:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:28 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 08:52:28 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:28 INFO - 'config_files': ('talos/linux_config.py',), 08:52:28 INFO - 'default_actions': ('clobber', 08:52:28 INFO - 'read-buildbot-config', 08:52:28 INFO - 'download-and-extract', 08:52:28 INFO - 'populate-webroot', 08:52:28 INFO - 'create-virtualenv', 08:52:28 INFO - 'install', 08:52:28 INFO - 'run-tests'), 08:52:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:28 INFO - 'download_minidump_stackwalk': True, 08:52:28 INFO - 'download_symbols': 'ondemand', 08:52:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:28 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:28 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:28 INFO - 'gecko_profile': False, 08:52:28 INFO - 'gecko_profile_interval': 0, 08:52:28 INFO - 'installer_path': 'installer.exe', 08:52:28 INFO - 'log_level': 'info', 08:52:28 INFO - 'log_name': 'talos', 08:52:28 INFO - 'log_to_console': True, 08:52:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:52:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:52:28 INFO - 'opt_config_files': (), 08:52:28 INFO - 'pip_index': False, 08:52:28 INFO - 'suite': 'dromaeojs', 08:52:28 INFO - 'system_bits': '32', 08:52:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:28 INFO - 'title': 'talos-linux64-ix-047', 08:52:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:28 INFO - 'use_talos_json': True, 08:52:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:28 INFO - 'work_dir': 'build'} 08:52:28 INFO - [mozharness: 2017-04-15 15:52:28.144789Z] Running clobber step. 08:52:28 INFO - Running pre-action listener: _resource_record_pre_action 08:52:28 INFO - Running main action method: clobber 08:52:28 INFO - rmtree: /builds/slave/test/build 08:52:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:29 INFO - Running post-action listener: _resource_record_post_action 08:52:29 INFO - [mozharness: 2017-04-15 15:52:29.074464Z] Finished clobber step (success) 08:52:29 INFO - [mozharness: 2017-04-15 15:52:29.074564Z] Running read-buildbot-config step. 08:52:29 INFO - Running pre-action listener: _resource_record_pre_action 08:52:29 INFO - Running main action method: read_buildbot_config 08:52:29 INFO - Using buildbot properties: 08:52:29 INFO - { 08:52:29 INFO - "project": "", 08:52:29 INFO - "product": "firefox", 08:52:29 INFO - "who": "ffxbld@noreply.mozilla.org", 08:52:29 INFO - "installer_path": "public/build/target.tar.bz2", 08:52:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:52:29 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 08:52:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 08:52:29 INFO - "stage_platform": "linux64", 08:52:29 INFO - "basedir": "/builds/slave/test", 08:52:29 INFO - "buildnumber": 132, 08:52:29 INFO - "platform": "ubuntu64_hw", 08:52:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:52:29 INFO - "slavebuilddir": "test", 08:52:29 INFO - "taskId": "e9BCt_z2Qveyx4VM9p5irQ", 08:52:29 INFO - "branch": "mozilla-aurora", 08:52:29 INFO - "script_repo_revision": "production", 08:52:29 INFO - "revision": "9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e", 08:52:29 INFO - "slavename": "talos-linux64-ix-047", 08:52:29 INFO - "repo_path": "releases/mozilla-aurora" 08:52:29 INFO - } 08:52:29 INFO - Finding installer, test and symbols from parent task. 08:52:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e9BCt_z2Qveyx4VM9p5irQ'}, attempt #1 08:52:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e9BCt_z2Qveyx4VM9p5irQ'}, attempt #1 08:52:30 INFO - Task dependencies: JYhDoRclQjidsZE4fUkjZw 08:52:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw'}, attempt #1 08:52:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.tar.bz2 08:52:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/test_packages.json 08:52:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.crashreporter-symbols.zip 08:52:30 INFO - Running post-action listener: _resource_record_post_action 08:52:30 INFO - [mozharness: 2017-04-15 15:52:30.457346Z] Finished read-buildbot-config step (success) 08:52:30 INFO - [mozharness: 2017-04-15 15:52:30.457567Z] Running download-and-extract step. 08:52:30 INFO - Running pre-action listener: _resource_record_pre_action 08:52:30 INFO - Running main action method: download_and_extract 08:52:30 INFO - mkdir: /builds/slave/test/build/tests 08:52:30 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')]} 08:52:30 INFO - trying https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.test_packages.json 08:52:30 INFO - Downloading https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:52:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:52:31 INFO - Downloaded 896 bytes. 08:52:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:52:31 INFO - Using the following test package requirements: 08:52:31 INFO - {u'common': [u'target.common.tests.zip'], 08:52:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:52:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:52:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:52:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:52:31 INFO - u'mozbase': [u'target.common.tests.zip'], 08:52:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:52:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:52:31 INFO - u'web-platform': [u'target.common.tests.zip', 08:52:31 INFO - u'target.web-platform.tests.zip'], 08:52:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:52:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:52:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.common.tests.zip 08:52:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:52:31 INFO - Fetch https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.common.tests.zip into memory 08:52:32 INFO - Expected file size: 59546753 08:52:32 INFO - Obtained file size: 59546753 08:52:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:52:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.common.tests.zip 08:52:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:52:41 INFO - Fetch https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.common.tests.zip into memory 08:52:42 INFO - Expected file size: 59546753 08:52:42 INFO - Obtained file size: 59546753 08:52:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.talos.tests.zip 08:52:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:52:53 INFO - Fetch https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.talos.tests.zip into memory 08:52:54 INFO - Expected file size: 14404424 08:52:54 INFO - Obtained file size: 14404424 08:52: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')]} 08:52:54 INFO - trying https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.tar.bz2 08:52:54 INFO - Downloading https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:52:56 INFO - Downloaded 66572204 bytes. 08:52:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.tar.bz2 08:52:56 INFO - mkdir: /builds/slave/test/properties 08:52:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:52:56 INFO - Writing to file /builds/slave/test/properties/build_url 08:52:56 INFO - Contents: 08:52:56 INFO - build_url:https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.tar.bz2 08:52:56 INFO - Running post-action listener: _resource_record_post_action 08:52:56 INFO - Running post-action listener: set_extra_try_arguments 08:52:56 INFO - [mozharness: 2017-04-15 15:52:56.349932Z] Finished download-and-extract step (success) 08:52:56 INFO - [mozharness: 2017-04-15 15:52:56.350075Z] Running populate-webroot step. 08:52:56 INFO - Running pre-action listener: _resource_record_pre_action 08:52:56 INFO - Running main action method: populate_webroot 08:52:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:52:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:52:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:52:56 INFO - '--tppagecycles', 08:52:56 INFO - '7'], 08:52:56 INFO - 'tests': ['tsvgm']}}, 08:52:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:52:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:52:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:52:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:52:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:52:56 INFO - 'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:52:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:52:56 INFO - 'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['damp', 'tps']}, 08:52:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:52:56 INFO - 'tests': ['damp', 'tps']}, 08:52:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['dromaeo_dom']}, 08:52:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:52:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['basic_compositor_video']}, 08:52:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:52:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['a11yr', 08:52:56 INFO - 'ts_paint', 08:52:56 INFO - 'tpaint', 08:52:56 INFO - 'sessionrestore', 08:52:56 INFO - 'sessionrestore_no_auto_restore', 08:52:56 INFO - 'tabpaint']}, 08:52:56 INFO - 'other-e10s': {'tests': ['a11yr', 08:52:56 INFO - 'ts_paint', 08:52:56 INFO - 'tpaint', 08:52:56 INFO - 'sessionrestore', 08:52:56 INFO - 'sessionrestore_no_auto_restore', 08:52:56 INFO - 'tabpaint']}, 08:52:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:52:56 INFO - 'ts_paint', 08:52:56 INFO - 'tpaint', 08:52:56 INFO - 'sessionrestore', 08:52:56 INFO - 'sessionrestore_no_auto_restore', 08:52:56 INFO - 'tabpaint']}, 08:52:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:52:56 INFO - 'ts_paint', 08:52:56 INFO - 'tpaint', 08:52:56 INFO - 'sessionrestore', 08:52:56 INFO - 'sessionrestore_no_auto_restore', 08:52:56 INFO - 'tabpaint']}, 08:52:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['a11yr', 08:52:56 INFO - 'ts_paint', 08:52:56 INFO - 'tpaint', 08:52:56 INFO - 'sessionrestore', 08:52:56 INFO - 'sessionrestore_no_auto_restore', 08:52:56 INFO - 'tabpaint']}, 08:52:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['a11yr', 08:52:56 INFO - 'ts_paint', 08:52:56 INFO - 'tpaint', 08:52:56 INFO - 'sessionrestore', 08:52:56 INFO - 'sessionrestore_no_auto_restore', 08:52:56 INFO - 'tabpaint']}, 08:52:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['tsvgx', 08:52:56 INFO - 'tsvgr_opacity', 08:52:56 INFO - 'tart', 08:52:56 INFO - 'tscrollx', 08:52:56 INFO - 'cart', 08:52:56 INFO - 'tsvg_static']}, 08:52:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:52:56 INFO - 'tsvgr_opacity', 08:52:56 INFO - 'tart', 08:52:56 INFO - 'tscrollx', 08:52:56 INFO - 'cart', 08:52:56 INFO - 'tsvg_static']}, 08:52:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:52:56 INFO - 'talos_options': ['--disable-e10s'], 08:52:56 INFO - 'tests': ['tp5o']}, 08:52:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:52:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:52:56 INFO - 'talos_options': ['--disable-e10s', 08:52:56 INFO - '--xperf_path', 08:52:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:56 INFO - 'tests': ['tp5n']}, 08:52:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:52:56 INFO - 'talos_options': ['--xperf_path', 08:52:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:56 INFO - 'tests': ['tp5n']}}, 08:52:56 INFO - 'talos.zip': {'path': '', 08:52:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:52:56 INFO - Running post-action listener: _resource_record_post_action 08:52:56 INFO - [mozharness: 2017-04-15 15:52:56.364352Z] Finished populate-webroot step (success) 08:52:56 INFO - [mozharness: 2017-04-15 15:52:56.364443Z] Running create-virtualenv step. 08:52:56 INFO - Running pre-action listener: _resource_record_pre_action 08:52:56 INFO - Running main action method: create_virtualenv 08:52:56 INFO - Creating virtualenv /builds/slave/test/build/venv 08:52:56 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 08:52:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:52:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:52:56 INFO - Using real prefix '/usr' 08:52:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:52:56 INFO - Installing distribute.............................................................................................................................................................................................done. 08:52:58 INFO - Installing pip.................done. 08:52:58 INFO - Return code: 0 08:52:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:52:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:52:59 INFO - Reading from file tmpfile_stdout 08:52:59 INFO - Output received: 08:52:59 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) 08:52:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52:59 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')]} 08:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:59 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')]} 08:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:59 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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:52:59 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 08:52:59 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 08:52:59 INFO - Using env: {'DISPLAY': ':0', 08:52:59 INFO - 'HOME': '/home/cltbld', 08:52:59 INFO - 'LANG': 'en_US.UTF-8', 08:52:59 INFO - 'LANGUAGE': 'en_US:en', 08:52:59 INFO - 'LOGNAME': 'cltbld', 08:52:59 INFO - 'MAIL': '/var/mail/cltbld', 08:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:59 INFO - 'MOZ_NO_REMOTE': '1', 08:52:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:59 INFO - 'NO_EM_RESTART': '1', 08:52:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:59 INFO - 'PWD': '/builds/slave/test', 08:52:59 INFO - 'SHELL': '/bin/bash', 08:52:59 INFO - 'SHLVL': '1', 08:52:59 INFO - 'TERM': 'linux', 08:52:59 INFO - 'TMOUT': '86400', 08:52:59 INFO - 'USER': 'cltbld', 08:52:59 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:59 INFO - '_': '/tools/buildbot/bin/python'} 08:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:59 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 08:52:59 INFO - Cleaning up... 08:52:59 INFO - Return code: 0 08:52:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:52:59 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')]} 08:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:59 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')]} 08:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:59 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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:52:59 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 08:52:59 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 08:52:59 INFO - Using env: {'DISPLAY': ':0', 08:52:59 INFO - 'HOME': '/home/cltbld', 08:52:59 INFO - 'LANG': 'en_US.UTF-8', 08:52:59 INFO - 'LANGUAGE': 'en_US:en', 08:52:59 INFO - 'LOGNAME': 'cltbld', 08:52:59 INFO - 'MAIL': '/var/mail/cltbld', 08:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:59 INFO - 'MOZ_NO_REMOTE': '1', 08:52:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:59 INFO - 'NO_EM_RESTART': '1', 08:52:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:59 INFO - 'PWD': '/builds/slave/test', 08:52:59 INFO - 'SHELL': '/bin/bash', 08:52:59 INFO - 'SHLVL': '1', 08:52:59 INFO - 'TERM': 'linux', 08:52:59 INFO - 'TMOUT': '86400', 08:52:59 INFO - 'USER': 'cltbld', 08:52:59 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:59 INFO - '_': '/tools/buildbot/bin/python'} 08:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:59 INFO - Downloading/unpacking psutil>=3.1.1 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:53:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:01 INFO - Installing collected packages: psutil 08:53:01 INFO - Running setup.py install for psutil 08:53:01 INFO - building 'psutil._psutil_linux' extension 08:53:01 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 08:53:01 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 08:53:01 INFO - building 'psutil._psutil_posix' extension 08:53:01 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 08:53:02 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 08:53:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:02 INFO - Successfully installed psutil 08:53:02 INFO - Cleaning up... 08:53:02 INFO - Return code: 0 08:53:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:53:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:53:02 INFO - Using env: {'DISPLAY': ':0', 08:53:02 INFO - 'HOME': '/home/cltbld', 08:53:02 INFO - 'LANG': 'en_US.UTF-8', 08:53:02 INFO - 'LANGUAGE': 'en_US:en', 08:53:02 INFO - 'LOGNAME': 'cltbld', 08:53:02 INFO - 'MAIL': '/var/mail/cltbld', 08:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:02 INFO - 'MOZ_NO_REMOTE': '1', 08:53:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:02 INFO - 'NO_EM_RESTART': '1', 08:53:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:02 INFO - 'PWD': '/builds/slave/test', 08:53:02 INFO - 'SHELL': '/bin/bash', 08:53:02 INFO - 'SHLVL': '1', 08:53:02 INFO - 'TERM': 'linux', 08:53:02 INFO - 'TMOUT': '86400', 08:53:02 INFO - 'USER': 'cltbld', 08:53:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:02 INFO - '_': '/tools/buildbot/bin/python'} 08:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:53:02 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 08:53:02 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 08:53:02 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 08:53:02 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 08:53:02 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 08:53:02 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 08:53:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:04 INFO - Installing collected packages: mozsystemmonitor 08:53:04 INFO - Running setup.py install for mozsystemmonitor 08:53:04 INFO - Successfully installed mozsystemmonitor 08:53:04 INFO - Cleaning up... 08:53:04 INFO - Return code: 0 08:53:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:04 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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:53:04 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 08:53:04 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 08:53:04 INFO - Using env: {'DISPLAY': ':0', 08:53:04 INFO - 'HOME': '/home/cltbld', 08:53:04 INFO - 'LANG': 'en_US.UTF-8', 08:53:04 INFO - 'LANGUAGE': 'en_US:en', 08:53:04 INFO - 'LOGNAME': 'cltbld', 08:53:04 INFO - 'MAIL': '/var/mail/cltbld', 08:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:04 INFO - 'MOZ_NO_REMOTE': '1', 08:53:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:04 INFO - 'NO_EM_RESTART': '1', 08:53:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:04 INFO - 'PWD': '/builds/slave/test', 08:53:04 INFO - 'SHELL': '/bin/bash', 08:53:04 INFO - 'SHLVL': '1', 08:53:04 INFO - 'TERM': 'linux', 08:53:04 INFO - 'TMOUT': '86400', 08:53:04 INFO - 'USER': 'cltbld', 08:53:04 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:04 INFO - '_': '/tools/buildbot/bin/python'} 08:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:04 INFO - Downloading/unpacking jsonschema==2.5.1 08:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:07 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:07 INFO - warning: no files found matching '*.txt' 08:53:07 INFO - no previously-included directories found matching 'build' 08:53:07 INFO - no previously-included directories found matching 'dist' 08:53:07 INFO - no previously-included directories found matching '.git*' 08:53:07 INFO - Installing collected packages: jsonschema, functools32 08:53:07 INFO - Running setup.py install for functools32 08:53:07 INFO - warning: no files found matching '*.txt' 08:53:07 INFO - no previously-included directories found matching 'build' 08:53:07 INFO - no previously-included directories found matching 'dist' 08:53:07 INFO - no previously-included directories found matching '.git*' 08:53:07 INFO - Successfully installed jsonschema functools32 08:53:07 INFO - Cleaning up... 08:53:07 INFO - Return code: 0 08:53:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:53:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:53:07 INFO - Using env: {'DISPLAY': ':0', 08:53:07 INFO - 'HOME': '/home/cltbld', 08:53:07 INFO - 'LANG': 'en_US.UTF-8', 08:53:07 INFO - 'LANGUAGE': 'en_US:en', 08:53:07 INFO - 'LOGNAME': 'cltbld', 08:53:07 INFO - 'MAIL': '/var/mail/cltbld', 08:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:07 INFO - 'MOZ_NO_REMOTE': '1', 08:53:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:07 INFO - 'NO_EM_RESTART': '1', 08:53:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:07 INFO - 'PWD': '/builds/slave/test', 08:53:07 INFO - 'SHELL': '/bin/bash', 08:53:07 INFO - 'SHLVL': '1', 08:53:07 INFO - 'TERM': 'linux', 08:53:07 INFO - 'TMOUT': '86400', 08:53:07 INFO - 'USER': 'cltbld', 08:53:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:07 INFO - '_': '/tools/buildbot/bin/python'} 08:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:07 INFO - Cleaning up... 08:53:07 INFO - Return code: 0 08:53:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:53:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:53:07 INFO - Using env: {'DISPLAY': ':0', 08:53:07 INFO - 'HOME': '/home/cltbld', 08:53:07 INFO - 'LANG': 'en_US.UTF-8', 08:53:07 INFO - 'LANGUAGE': 'en_US:en', 08:53:07 INFO - 'LOGNAME': 'cltbld', 08:53:07 INFO - 'MAIL': '/var/mail/cltbld', 08:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:07 INFO - 'MOZ_NO_REMOTE': '1', 08:53:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:07 INFO - 'NO_EM_RESTART': '1', 08:53:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:07 INFO - 'PWD': '/builds/slave/test', 08:53:07 INFO - 'SHELL': '/bin/bash', 08:53:07 INFO - 'SHLVL': '1', 08:53:07 INFO - 'TERM': 'linux', 08:53:07 INFO - 'TMOUT': '86400', 08:53:07 INFO - 'USER': 'cltbld', 08:53:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:07 INFO - '_': '/tools/buildbot/bin/python'} 08:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:07 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:07 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 08:53:07 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 08:53:07 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 08:53:07 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 08:53:07 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 08:53:07 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 08:53:09 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:09 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 08:53:09 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 08:53:09 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 08:53:09 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 08:53:09 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 08:53:09 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 08:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:10 INFO - Downloading docopt-0.6.1.tar.gz 08:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:10 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:10 INFO - Running setup.py install for blobuploader 08:53:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:10 INFO - Running setup.py install for requests 08:53:11 INFO - Running setup.py install for docopt 08:53:11 INFO - Successfully installed blobuploader requests docopt 08:53:11 INFO - Cleaning up... 08:53:11 INFO - Return code: 0 08:53:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:11 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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:53:11 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 08:53:11 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 08:53:11 INFO - Using env: {'DISPLAY': ':0', 08:53:11 INFO - 'HOME': '/home/cltbld', 08:53:11 INFO - 'LANG': 'en_US.UTF-8', 08:53:11 INFO - 'LANGUAGE': 'en_US:en', 08:53:11 INFO - 'LOGNAME': 'cltbld', 08:53:11 INFO - 'MAIL': '/var/mail/cltbld', 08:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:11 INFO - 'MOZ_NO_REMOTE': '1', 08:53:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:11 INFO - 'NO_EM_RESTART': '1', 08:53:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:11 INFO - 'PWD': '/builds/slave/test', 08:53:11 INFO - 'SHELL': '/bin/bash', 08:53:11 INFO - 'SHLVL': '1', 08:53:11 INFO - 'TERM': 'linux', 08:53:11 INFO - 'TMOUT': '86400', 08:53:11 INFO - 'USER': 'cltbld', 08:53:11 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:11 INFO - '_': '/tools/buildbot/bin/python'} 08:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:11 INFO - Running setup.py (path:/tmp/pip-ya6PL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:11 INFO - Running setup.py (path:/tmp/pip-5s4o7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:11 INFO - Running setup.py (path:/tmp/pip-mVM2CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:11 INFO - Running setup.py (path:/tmp/pip-iMxCzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:11 INFO - Running setup.py (path:/tmp/pip-XB6sab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:11 INFO - Running setup.py (path:/tmp/pip-NdRZ5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:11 INFO - Running setup.py (path:/tmp/pip-PK83fQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:12 INFO - Running setup.py (path:/tmp/pip-dfRFYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:12 INFO - Running setup.py (path:/tmp/pip-E1gPqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:12 INFO - Running setup.py (path:/tmp/pip-pFz_Xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:12 INFO - Running setup.py (path:/tmp/pip-mQigjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:12 INFO - Running setup.py (path:/tmp/pip-zNlyx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:12 INFO - Running setup.py (path:/tmp/pip-voaCdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:12 INFO - Running setup.py (path:/tmp/pip-DOlT04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:12 INFO - Running setup.py (path:/tmp/pip-Naoz5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:12 INFO - Running setup.py (path:/tmp/pip-_fU5It-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:12 INFO - Running setup.py (path:/tmp/pip-FrNfEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:12 INFO - Running setup.py install for manifestparser 08:53:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:12 INFO - Running setup.py install for mozcrash 08:53:12 INFO - Running setup.py install for mozdebug 08:53:13 INFO - Running setup.py install for mozdevice 08:53:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozfile 08:53:13 INFO - Running setup.py install for mozhttpd 08:53:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozinfo 08:53:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozInstall 08:53:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozleak 08:53:13 INFO - Running setup.py install for mozlog 08:53:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for moznetwork 08:53:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozprocess 08:53:13 INFO - Running setup.py install for mozprofile 08:53:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Running setup.py install for mozrunner 08:53:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Running setup.py install for mozscreenshot 08:53:14 INFO - Running setup.py install for moztest 08:53:14 INFO - Running setup.py install for mozversion 08:53:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:14 INFO - Cleaning up... 08:53:14 INFO - Return code: 0 08:53:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:14 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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:53:14 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 08:53:14 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 08:53:14 INFO - Using env: {'DISPLAY': ':0', 08:53:14 INFO - 'HOME': '/home/cltbld', 08:53:14 INFO - 'LANG': 'en_US.UTF-8', 08:53:14 INFO - 'LANGUAGE': 'en_US:en', 08:53:14 INFO - 'LOGNAME': 'cltbld', 08:53:14 INFO - 'MAIL': '/var/mail/cltbld', 08:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:14 INFO - 'MOZ_NO_REMOTE': '1', 08:53:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:14 INFO - 'NO_EM_RESTART': '1', 08:53:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:14 INFO - 'PWD': '/builds/slave/test', 08:53:14 INFO - 'SHELL': '/bin/bash', 08:53:14 INFO - 'SHLVL': '1', 08:53:14 INFO - 'TERM': 'linux', 08:53:14 INFO - 'TMOUT': '86400', 08:53:14 INFO - 'USER': 'cltbld', 08:53:14 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:14 INFO - '_': '/tools/buildbot/bin/python'} 08:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:14 INFO - Running setup.py (path:/tmp/pip-4doPEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:14 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)) 08:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:14 INFO - Running setup.py (path:/tmp/pip-XcUYzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:14 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)) 08:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:14 INFO - Running setup.py (path:/tmp/pip-Dbeei_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:14 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)) 08:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:14 INFO - Running setup.py (path:/tmp/pip-XfHa3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:15 INFO - Running setup.py (path:/tmp/pip-tbnckM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:15 INFO - Running setup.py (path:/tmp/pip-6SSRli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:15 INFO - Running setup.py (path:/tmp/pip-wiPP88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:15 INFO - Running setup.py (path:/tmp/pip-NcTjy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:15 INFO - Running setup.py (path:/tmp/pip-dhQFy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:15 INFO - Running setup.py (path:/tmp/pip-F6ROBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:15 INFO - Running setup.py (path:/tmp/pip-Y_Ne7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:15 INFO - Running setup.py (path:/tmp/pip-I2ia4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:15 INFO - Running setup.py (path:/tmp/pip-g3mvZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:15 INFO - Running setup.py (path:/tmp/pip-jqiDzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:15 INFO - Running setup.py (path:/tmp/pip-N_FVA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:15 INFO - Running setup.py (path:/tmp/pip-6JwAeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:15 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)) 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:15 INFO - Running setup.py (path:/tmp/pip-v3NQ7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:16 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)) 08:53:16 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)) 08:53:16 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)) 08:53:16 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)) 08:53:16 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)) 08:53:16 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)) 08:53:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:18 INFO - Downloading blessings-1.6.tar.gz 08:53:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:53:18 INFO - Installing collected packages: blessings 08:53:18 INFO - Running setup.py install for blessings 08:53:18 INFO - Successfully installed blessings 08:53:18 INFO - Cleaning up... 08:53:18 INFO - Return code: 0 08:53:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:53:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:18 INFO - Reading from file tmpfile_stdout 08:53:18 INFO - Current package versions: 08:53:18 INFO - argparse == 1.2.1 08:53:18 INFO - blessings == 1.6 08:53:18 INFO - blobuploader == 1.2.4 08:53:18 INFO - docopt == 0.6.1 08:53:18 INFO - functools32 == 3.2.3-2 08:53:18 INFO - jsonschema == 2.5.1 08:53:18 INFO - manifestparser == 1.1 08:53:18 INFO - mozInstall == 1.12 08:53:18 INFO - mozcrash == 1.0 08:53:18 INFO - mozdebug == 0.1 08:53:18 INFO - mozdevice == 0.49 08:53:18 INFO - mozfile == 1.2 08:53:18 INFO - mozhttpd == 0.7 08:53:18 INFO - mozinfo == 0.9 08:53:18 INFO - mozleak == 0.1 08:53:18 INFO - mozlog == 3.4 08:53:18 INFO - moznetwork == 0.27 08:53:18 INFO - mozprocess == 0.25 08:53:18 INFO - mozprofile == 0.28 08:53:18 INFO - mozrunner == 6.13 08:53:18 INFO - mozscreenshot == 0.1 08:53:18 INFO - mozsystemmonitor == 0.3 08:53:18 INFO - moztest == 0.8 08:53:18 INFO - mozversion == 1.4 08:53:18 INFO - psutil == 3.1.1 08:53:18 INFO - requests == 1.2.3 08:53:18 INFO - wsgiref == 0.1.2 08:53:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:18 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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:53:18 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 08:53:18 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 08:53:18 INFO - Using env: {'DISPLAY': ':0', 08:53:18 INFO - 'HOME': '/home/cltbld', 08:53:18 INFO - 'LANG': 'en_US.UTF-8', 08:53:18 INFO - 'LANGUAGE': 'en_US:en', 08:53:18 INFO - 'LOGNAME': 'cltbld', 08:53:18 INFO - 'MAIL': '/var/mail/cltbld', 08:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:18 INFO - 'MOZ_NO_REMOTE': '1', 08:53:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:18 INFO - 'NO_EM_RESTART': '1', 08:53:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:18 INFO - 'PWD': '/builds/slave/test', 08:53:18 INFO - 'SHELL': '/bin/bash', 08:53:18 INFO - 'SHLVL': '1', 08:53:18 INFO - 'TERM': 'linux', 08:53:18 INFO - 'TMOUT': '86400', 08:53:18 INFO - 'USER': 'cltbld', 08:53:18 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:18 INFO - '_': '/tools/buildbot/bin/python'} 08:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 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)) 08:53:18 INFO - Cleaning up... 08:53:18 INFO - Return code: 0 08:53:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:18 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 0xc0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xead310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, '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': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', '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 08:53:18 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 08:53:18 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 08:53:18 INFO - Using env: {'DISPLAY': ':0', 08:53:18 INFO - 'HOME': '/home/cltbld', 08:53:18 INFO - 'LANG': 'en_US.UTF-8', 08:53:18 INFO - 'LANGUAGE': 'en_US:en', 08:53:18 INFO - 'LOGNAME': 'cltbld', 08:53:18 INFO - 'MAIL': '/var/mail/cltbld', 08:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:18 INFO - 'MOZ_NO_REMOTE': '1', 08:53:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:18 INFO - 'NO_EM_RESTART': '1', 08:53:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:18 INFO - 'PWD': '/builds/slave/test', 08:53:18 INFO - 'SHELL': '/bin/bash', 08:53:18 INFO - 'SHLVL': '1', 08:53:18 INFO - 'TERM': 'linux', 08:53:18 INFO - 'TMOUT': '86400', 08:53:18 INFO - 'USER': 'cltbld', 08:53:18 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:18 INFO - '_': '/tools/buildbot/bin/python'} 08:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:53:19 INFO - Cleaning up... 08:53:19 INFO - Return code: 0 08:53:19 INFO - Running post-action listener: _resource_record_post_action 08:53:19 INFO - Running post-action listener: _start_resource_monitoring 08:53:19 INFO - Starting resource monitoring. 08:53:19 INFO - [mozharness: 2017-04-15 15:53:19.205359Z] Finished create-virtualenv step (success) 08:53:19 INFO - [mozharness: 2017-04-15 15:53:19.205867Z] Running install step. 08:53:19 INFO - Running pre-action listener: _resource_record_pre_action 08:53:19 INFO - Running main action method: install 08:53:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:19 INFO - Reading from file tmpfile_stdout 08:53:19 INFO - Detecting whether we're running mozinstall >=1.0... 08:53:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:53:19 INFO - Reading from file tmpfile_stdout 08:53:19 INFO - Output received: 08:53:19 INFO - Usage: mozinstall [options] installer 08:53:19 INFO - Options: 08:53:19 INFO - -h, --help show this help message and exit 08:53:19 INFO - -d DEST, --destination=DEST 08:53:19 INFO - Directory to install application into. [default: 08:53:19 INFO - "/builds/slave/test"] 08:53:19 INFO - --app=APP Application being installed. [default: firefox] 08:53:19 INFO - mkdir: /builds/slave/test/build/application 08:53:19 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'] 08:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:53:36 INFO - Reading from file tmpfile_stdout 08:53:36 INFO - Output received: 08:53:36 INFO - /builds/slave/test/build/application/firefox/firefox 08:53:36 INFO - Running post-action listener: _resource_record_post_action 08:53:36 INFO - [mozharness: 2017-04-15 15:53:36.326943Z] Finished install step (success) 08:53:36 INFO - [mozharness: 2017-04-15 15:53:36.327218Z] Running run-tests step. 08:53:36 INFO - Running pre-action listener: _resource_record_pre_action 08:53:36 INFO - Running main action method: run_tests 08:53:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:53:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:53:36 INFO - Python 2.7.3 08:53:36 INFO - Return code: 0 08:53:36 INFO - grabbing minidump binary from tooltool 08:53:36 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')]} 08:53:36 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 0xeaa400>, '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 0xeab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeab840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:53:36 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 08:53:36 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 08:53:36 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 08:53:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:36 INFO - Return code: 0 08:53:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:53:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:53:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:53:36 INFO - ENV: RUST_BACKTRACE is now 1 08:53:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:53:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:53:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 08:53:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 08:53:36 INFO - Using env: {'DISPLAY': ':0', 08:53:36 INFO - 'HOME': '/home/cltbld', 08:53:36 INFO - 'LANG': 'en_US.UTF-8', 08:53:36 INFO - 'LANGUAGE': 'en_US:en', 08:53:36 INFO - 'LOGNAME': 'cltbld', 08:53:36 INFO - 'MAIL': '/var/mail/cltbld', 08:53:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:53:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:36 INFO - 'MOZ_NO_REMOTE': '1', 08:53:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:53:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:36 INFO - 'NO_EM_RESTART': '1', 08:53:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:36 INFO - 'PWD': '/builds/slave/test', 08:53:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:53:36 INFO - 'RUST_BACKTRACE': '1', 08:53:36 INFO - 'SHELL': '/bin/bash', 08:53:36 INFO - 'SHLVL': '1', 08:53:36 INFO - 'TERM': 'linux', 08:53:36 INFO - 'TMOUT': '86400', 08:53:36 INFO - 'USER': 'cltbld', 08:53:36 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569', 08:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:36 INFO - '_': '/tools/buildbot/bin/python'} 08:53:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 08:53:36 INFO - mozversion application_buildid: 20170415150250 08:53:36 INFO - mozversion application_changeset: 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e 08:53:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:53:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:36 INFO - mozversion application_name: Firefox 08:53:36 INFO - mozversion application_remotingname: firefox-dev 08:53:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:53:36 INFO - mozversion application_vendor: Mozilla 08:53:36 INFO - mozversion application_version: 54.0a2 08:53:36 INFO - mozversion platform_buildid: 20170415150250 08:53:36 INFO - mozversion platform_changeset: 9da0d3c7fc50cc406d66fc1f286a5cf2254dd51e 08:53:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:53:36 INFO - mozversion platform_version: 54.0a2 08:53:36 INFO - using testdate: 1492271616 08:53:36 INFO - actual date: 1492271616 08:53:36 INFO - starting webserver on 'localhost:41558' 08:53:36 INFO - SUITE-START | Running 2 tests 08:53:36 INFO - TEST-START | dromaeo_css 08:53:36 INFO - operating with platform_type : linux_ 08:53:36 INFO - Initialising browser for dromaeo_css test... 08:53:36 INFO - TEST-INFO | started process 21032 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuBVi4t/profile http://localhost:41558/getInfo.html) 08:53:40 INFO - PROCESS | 21032 | __metrics Screen width/height:1600/1200 08:53:40 INFO - PROCESS | 21032 | colorDepth:24 08:53:40 INFO - PROCESS | 21032 | Browser inner width/height: 1024/697 08:53:40 INFO - PROCESS | 21032 | __metrics 08:53:41 INFO - PROCESS | 21032 | 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" 08:53:41 INFO - PROCESS | 21032 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:53:42 INFO - TEST-INFO | 21032: exit 0 08:53:42 INFO - Browser initialized. 08:53:42 INFO - Running cycle 1/1 for dromaeo_css test... 08:53:42 INFO - TEST-INFO | started process 21124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuBVi4t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:54 INFO - PROCESS | 21124 | RSS: Main: 139812864 08:53:54 INFO - PROCESS | 21124 | 08:56:55 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41558/tests/dromaeo/cssquery-ext.html) 08:56:57 INFO - PROCESS | 21124 | RSS: Main: 281550848 08:56:57 INFO - PROCESS | 21124 | 08:58:43 INFO - PROCESS | 21124 | ************************* 08:58:43 INFO - PROCESS | 21124 | A coding exception was thrown and uncaught in a Task. 08:58:43 INFO - PROCESS | 21124 | 08:58:43 ERROR - PROCESS | 21124 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:43 INFO - PROCESS | 21124 | Full stack: 08:58:43 INFO - PROCESS | 21124 | ************************* 08:58:43 INFO - PROCESS | 21124 | ************************* 08:58:43 INFO - PROCESS | 21124 | A coding exception was thrown and uncaught in a Task. 08:58:43 INFO - PROCESS | 21124 | 08:58:43 ERROR - PROCESS | 21124 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:43 INFO - PROCESS | 21124 | Full stack: 08:58:43 INFO - PROCESS | 21124 | ************************* 08:58:43 INFO - PROCESS | 21124 | ************************* 08:58:43 INFO - PROCESS | 21124 | A coding exception was thrown and uncaught in a Task. 08:58:43 INFO - PROCESS | 21124 | 08:58:43 ERROR - PROCESS | 21124 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:43 INFO - PROCESS | 21124 | Full stack: 08:58:43 INFO - PROCESS | 21124 | ************************* 08:58:43 INFO - PROCESS | 21124 | ************************* 08:58:43 INFO - PROCESS | 21124 | A coding exception was thrown and uncaught in a Task. 08:58:43 INFO - PROCESS | 21124 | 08:58:43 ERROR - PROCESS | 21124 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:43 INFO - PROCESS | 21124 | Full stack: 08:58:43 INFO - PROCESS | 21124 | ************************* 08:59:59 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-ext.html (next: http://localhost:41558/tests/dromaeo/cssquery-jquery.html) 08:59:59 INFO - PROCESS | 21124 | RSS: Main: 227930112 08:59:59 INFO - PROCESS | 21124 | 09:03:01 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41558/tests/dromaeo/cssquery-mootools.html) 09:03:02 INFO - PROCESS | 21124 | RSS: Main: 217669632 09:03:02 INFO - PROCESS | 21124 | 09:06:04 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41558/tests/dromaeo/cssquery-prototype.html) 09:06:04 INFO - PROCESS | 21124 | RSS: Main: 170328064 09:06:04 INFO - PROCESS | 21124 | 09:09:07 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41558/tests/dromaeo/cssquery-yui.html) 09:09:08 INFO - PROCESS | 21124 | RSS: Main: 177573888 09:09:08 INFO - PROCESS | 21124 | 09:12:10 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-yui.html (next: http://localhost:41558/tests/dromaeo/cssquery-dojo.html) 09:12:11 INFO - PROCESS | 21124 | RSS: Main: 262639616 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - PROCESS | 21124 | __start_tp_report 09:12:11 INFO - PROCESS | 21124 | _x_x_mozilla_page_load 09:12:11 INFO - PROCESS | 21124 | _x_x_mozilla_page_load_details 09:12:11 INFO - PROCESS | 21124 | |i|pagename|runs| 09:12:11 INFO - PROCESS | 21124 | |0;dojo.html;3361.7646764726464;3370.528126062351;3370.679785420384;3389.508521264418;3412.1810765416294;5618.101103823389;5623.437656234374;5656.575756818244;5667.724761928564;5708.800191993281;5712.628679135863;5809.302883653967;5820.767169313233;5823.854403639911;5830.650160990333;5248.186531087668;5253.369595648527;5290.232916227144;5296.390915044762;5326.973365133188;5090.669106508084;5096.643234973553;5361.731913404315;5381.48875856793;5390.838274851761;5217.739113044351;5270.762815673284;5306.044911915854;5328.254044433782;5328.6802791832615;5353.839384818461;5355.544778693777;5362.249285100089;5365.543928766059;5366.087765079946;4987.276844857475;5208.583313334924;5283.973580132075;5332.973335133338;5339.919901201485;5515.862103447405;5641.633293835928;5703.258576385043;5708.457696518824;5726.141078838207;6143.293521245025;6179.907301390483;6183.845403864894;6193.225846769154;6197.1324014637985;6209.285932117819;6222.875542489125;6243.1883855099;6253.499720022387;6254.718537665816;5507.394186639466;5528.087865502187;5720.942790572124;5759.712014399263;5765.135229715576;6309.968450157811;6311.684415779285;6311.810645680637;6312.495000399957;6313.305536391085;6036.728347224385;6090.116933044684;6100.328963813977;6102.145699602059;6106.755729770848;5910.408959104141;5946.821595352147;6119.632822030692;6144.893919094517;6208.906866397007;6142.437386315926;6178.783742569079;6188.938110618837;6203.193584834033;6203.751849925952;5861.677607731615;5897;5899.174115623816;6113.6331820090045;6206.813795586139;4706.929396059062;4758.095961767253;4765.7140571565815;4772.689775164603;4784.665073444826;4108.301588729923;4122.154958233615;4168.791560421967;4222.514410842792;4275.572442755762;5939.406059394026;6017.06735456005;6025.487833534199;6029;6037.547183961133;6217.067439884054;6238.282597501346;6243.37566243372;6252.437280644764;6253.812385628438;5734.569907256972;5986.311574169026;5998.970005150034;6007.068904319837;6037.215162028908;5072.6195535335;5103.2855400244;5152.793888244503;5198.714070726146;5211.973940130275;6472.773452929219;6485.6108633482145;6493.870122597522;6502.544821862424;6514.250861150934;6188.690565471798;6210.099535567227;6233.096201050913;6253.6560489173535;6268.122462855113;6112.321110680095;6177.62934223948;6336.841578960563;6360.300366959538;6450.354964503512;6115.422885572139;6119.07606531266;6121.8493044937295;6124.655575319917;6125.17440701618;6228.937710622836;6249.9375006251175;6251.156093927261;6251.499880009497;6256.343083976299;5755;5871.7651293947765;5972;6005.069214271794;6069.332373439007;6043.667598281961;6044.788432404845;6058.151858739868;6058.303295121117;6081.6351018939;6024.216851809237;6030.517558595389;6033.577649564489;6048.274207095177;6062.302835174011;5786.247787787561;5805.3614102447855;5843.1235314701435;6013.248343957005;6068.787592434243;5986.521078313637;5991.2510936132985;5995.940040599713;6012.849678757891;6025.517958563217 09:12:11 INFO - PROCESS | 21124 | |1;ext.html;771.1671394893516;857.0572370392568;871.6034204436979;937.9296552758541;944.3248077624497;30877.536836947464;31247.68752312476;33519.994400168005;42848.14303713924;44213.557864421346;44426.333604995954;44501;44628.77685611568;44697.553024469744;44703.77648111755;9258.814823703538;9362.531873406337;9368.391054581465;9387.5306234688;9400.200982916425;7263.0921134858145;7484.02707648005;10625.84061239082;10889.074428673533;10892.673219803379;10994.230403871732;11023;11029.944850275777;11037.779244415107;11039.779204415907;10935.398553079578;10975.06711929487;10978.835317470244;10987;10998.890011099907;10399.116075133623;10449.164066874631;10450.79098418035;10489.318194317346;10710.41092739899;40346.394804077965;40377.19245615071;40490.39264411036;40550.39174412386;40564;4167.416561681367;4172.8122234499215;4223.619874211305;4260.978695106535;4302.935455968163;28754;28985.71014289851;29004.70995290041;29198.270043248875;29435.96974105896;4326.134773045404;4436.711613745097;4453.354263631788;4504.752238626874;4560.315952607102;235689;236634;237201.69794057126;237605;238018;52277;52548.73725631424;52730.73634631802;52753.472465274855;52833.207501887504;55411;55437.44562554323;55460.72269638706;55464.445355545926;55502.444975550534;16958.915205423895;17313;17335.56661083483;17343.65312693739;17349.045802480985;13397.66505837362;15187.468438604596;15199.620009499851;15239.466618668293;15296.235188240544;12095.57665481704;12678.80981785274;13067.738645227233;13214.603561893158;13478.123921945042;5534.52956498694;5572.6625609853345;5691.231683722727;5697.800576979786;5710.828675139753;1011.1758916483043;1043.6242952537082;1048.1587806849252;1052.85786418834;1087.0705546757408;28548.85725571359;28960.275993100342;29064;29105.12684619465;29313.413731725672;1105.15455676406;1108.800415925125;1128.1707944660668;1152.1358980764426;1158.0677554568485;14982.775258371134;15290.694186116438;15411.845881541265;15513.37946482128;15592.610184745472;319530;320241;320994;321068;321325;55877;55925.161122583195;56106;56171.43828561744;56175.438245617835;1537.5771662792822;1551.5578060253;1590.19695053997;1593.330801063556;1597.6085858964627;25744.8712756435;27082;27172;27288.317792054564;27397.58903616487;41339.379909301984;41446.58553414427;41456.79271603622;41534.58465415307;41543.58456415397;293221;294290;295270;295275;295916;33910.3217935635;34143.82928085344;34214.48678269778;34326.82836585801;34543;41772.79113604413;42066;43227;43270.78364608157;43352.78323608362;32364.838175809913;32605;32671.509927350635;32693.346133076728;32976.34047319088;32396.514052289705;32807;33144;33196;33772.66227337794;37938.24123517459;38290.234195316494;38349;38490.615093848704;38572.80713596414;31190.37619247557;31529.36941261208;31577.684223157474;31672.208194795316;32138.678613213568;24565.263042108767;25577.360565986;25607;25713.742862571882;26003.21990340293 09:12:11 INFO - PROCESS | 21124 | |2;jquery.html;419.47984499220973;452.2154062701214;467.42273292483776;478.4569762515887;479.52526998271713;15864.444744433948;18657.253709851608;20013.399598012064;20089.497762555944;20179.899100504514;19788.40634780959;21963.450913727123;22261.66607500889;22273.55452890941;22656.88671556644;6633;6720.764773232938;6937.826554336131;6971.930280697205;6977.930220697779;6805.013273075401;6815.863682726343;6864.313568643146;6868.2788307227775;6875.896861547081;6760.8309792255095;6785.253622101565;7340.4127669786285;7640;7664.233576642347;7451.180370159278;7589.582572958485;7634.847303053935;7638.045244344485;7647.235276472364;7519.323260906544;7525.4732168748205;7535.472516923817;7536.171021187665;7537.321641052332;24121.87939060311;25048.49903001948;25188.748112518824;25529.62673451193;25588.616170757454;3058.3271680230314;3064;3090;3097.6282846058616;3140.5446210299606;16148.192590370434;16218.351265949466;16242.350305987746;16432.014079155288;16538.50384488467;2194.4241991541517;2196.604611169989;2214.523877366374;2269.024319542596;2292.1748170658548;108531;111647.44176279148;112110;112558.43720781426;114481;14536.112582355358;18182.27270909149;18407.447776566725;18482.260709571467;18978.240870365305;28899.133026009255;29809.701902980694;29952.801887924237;31793;31863.681363186533;10208.234382421348;10422.739431514121;10567.471626418648;10595.364278143335;10745.78508429827;8847.24798392144;8883.644654213758;8912.465252084896;9072.319576031825;9091.909080909238;9023.458592484472;9247.12152345526;9255.167034966886;9365.250779937725;9451.33840631163;3513.4729790531114;3552.2717842842158;3787.3561494545993;3939.330313846653;3978.4629075074495;965.358036905464;976.0819988722053;977.2670497127154;977.5405559387077;981.3326937682444;21048.368548943556;21061.89469052645;21274.36176914695;21298.25456109029;21399.572008559742;966.3525437956586;971.2763990826738;982.7739619887466;987.9012098790064;993.4734590666958;10496.947515262527;11368.317900925971;11561.248518846416;11639.185257031926;11658.825117623242;310199;311137;395044;734783;747338;109367.45316273368;114086.42956785164;114797;114955;115074.424627878;1222.9510819567117;1237.2761934268374;1261.6719652890315;1263.5767018048873;1264.9367531623197;17649.91175044168;17957.910210449387;18202.45392638223;18223.544411389823;18255.361062362754;33194.50208246926;46532.30201547047;50584.494155057975;52667.73666131645;53307.4669253318;63396.68301658647;64162.358376417505;65202.673986629765;65679.67160164169;67004.32995670175;11934.820977685167;12005;12032.338221397908;12033.338166400581;12327.137100403244;13205.933970330087;13214.735705285648;13274.734505309647;13417.993650476059;13418;11726.648200553997;11830.46762895689;11896.286222826659;12070.879291206975;12243.632691019284;11047.944760276469;11207.43962801873;11307.208495405572;11434.94282528582;11523;12112.394380281126;12159.574414895436;12164.695882603006;12222.877771222174;12602.306873122066;11834.881651183377;11932.761344772882;12021.098417618889;12043.638690839289;12303;11878.762424751629;12048.337341446304;12132.878671213175;12216.144869859025;12298.446569904554 09:12:11 INFO - PROCESS | 21124 | |3;mootools.html;1543.7915881356014;1555.152441919154;1583.9604009899751;1588.7775711400404;1590.9761353579697;10709.46452677366;11478.311301321926;11479.081673466113;11488.540458381654;11498.770024599513;10665.78668426631;10975.06711929485;11369.602063927765;11393.430328483584;11409.20135590509;2441.7680320369536;2476.5046990601863;2478.628205769131;2480.255923223026;2481.5781317176125;2340.625499920013;2371.2649078785544;2484.3913241255827;2489.5643262429094;2490.663760392342;2387.236084452976;2414.782669559739;2431.0275889644195;2496.662950501677;2498.0632262901413;2270.2508172303183;2289.839711220215;2292.243559625328;2294.9885250573807;2421.067888862783;2278.4531712388907;2307.8730669813153;2319.095552734435;2323.8605683659034;2325.5232677301115;11210.215284930058;11347.092232621371;11362.602308919148;11365.772684546262;11393.772124557545;5701.372848986608;5821.650700957914;5822.417758224186;5831.125331200354;5834.5624078194305;3331.916702082443;3405.9148521287166;3445.2248244145117;3450.7929524228375;3468.9479657805155;1314.1195399082637;1326.356716992258;1330.9733805323935;1391.08883681189;1409.5912185466202;14857.554273371816;15274.465393711278;17134.914325428294;17426.825731742647;17580.736288955675;4566.95433045672;4663.580277775017;4681.391419115561;4691.225947718656;4701.8354357598;12664.936675316749;12668.80996785049;12670.936645316715;12680.93659531715;12682.936585317198;6020.217371741645;6025.096235564701;6025.969870150621;6028.367021462684;6031.125486804389;5558.277423934863;5559.749811258423;5584.5811564132855;5594.440555944454;5643.238162847963;3870.6903447724117;3952.26883026641;3982.6017398260524;4033.838646454109;4052.5744796796503;998.9150922171551;1002.1331548210745;1016.4917541229386;1028.6862506935306;1032.5766435761302;1030.7165529479453;1041.3335465302212;1044.9477526123662;1054.3146954479496;1054.8734151901667;6625.370589793962;6843.657817109125;6855.485838562127;6967.512274140863;7003.824904377329;987.3483500889522;1017.7506510904727;1033.9586416543254;1046.042870773234;1053.2153545608571;4356.760378179167;4370.344448332776;4393.077453734688;4410.029793445437;4440.245158323093;13807.309634518233;13987.300634968211;14163.433462661584;14289.714205715827;14294.070885392417;14254.643633909236;14505.839532837348;14708;14711.19088450124;14728.852711472962;8848.64605415789;10308;10528.315659482108;10530.526126324334;10531.368117912949;5836.270466191726;6852;6970.233274339717;7171.713131474891;7179;12557.372131393211;12590.811137833123;12789.680257993625;12952.158109722837;13093;12902.741945160857;12926.741465170831;12998.805017924928;13090.476380944656;13135.802962955373;9217.723468295962;9530.666426675032;9588.76028099303;9594.280428967995;9625.278104142077;10544.420056896637;10581.788364232518;10698.197635177237;10722.249442538947;10774.461276935965;8937.106289371011;8981.730548083568;9126.680566180152;9295.209907157967;9368.344215905094;8913.955430222808;8975.416597921114;8996.100389961213;9002.594883230402;9228.95385523068;8195.754127376189;8309.95845020771;8363.247307742333;8383.916160838313;8401.62192701342;5898.64608123514;5995.640261584319;6007.038873780174;6069.696515174312;6190.288116866618;6151.231096112986;6182.227221597301;6218.689065546795;6368.522360822864;6397.392247736458 09:12:11 INFO - PROCESS | 21124 | |4;prototype.html;413.0829558380394;434.80433804787845;435.2339881808018;442.250385596414;444.8665400379887;5326.946730532693;5327.307450031501;5376.919346209805;5403.999999999995;5404.54077399102;5122.795088196477;5294.338207724035;5299.920501192476;5307.787688492465;5342.076852333157;4544.954660428112;4573.977130114345;4592.74739889306;4602.332661764042;4604.769761511928;4567.977160114211;4571.948451856075;4574.336721175428;4636.81761150906;4654.511276315989;4391.802368893392;4636.675432719711;4662.0675864827;4664.976675116636;4668.883277918046;4586.3259910132565;4587.770611469413;4590.678652494326;4601.378813860119;4610;4456.64346852251;4460.397846290742;4461.42001539801;4477.462704475483;4482.372467854503;4908.509149085098;5267.47325267474;5269.314989051437;5271.604629652791;5295.425587728307;5285.2600635911;5291.550218231455;5306.204069389584;5313.282706834605;5320.334958130234;5501.229827824108;5516.365617953947;5534.418886016991;5557.221988921554;5582.385937546866;4631.884202894921;4795.664303498756;4875.731834749087;4879.780409881564;4929;5485.149801780691;5610.747516361774;5632.52123569493;5733.340665823442;5772.336181339116;4981.37732783402;5024.974875125601;5034.84895453137;5037.420696619863;5083.517065878736;5101.234814777738;5177.042247184283;5184.274201611777;5247.737613119405;5259.342582177228;5147.43003383964;5221.198686453167;5246.947530524722;5297.258383826267;5301.489075613432;5498.670079795225;5513.531349835309;5525.4474552545225;5529.225908372831;5530.336359636869;1726.927686775874;1796.6316905034437;1802.2340505285201;1811.6376724655122;1811.7101263797992;1433.2905211920165;1441.747694153519;1454.4255019267541;1455.3887367305756;1494.7220126791437;5355.357357117093;5369.704666243316;5396.4333744957;5427.213054107132;5479.972600136974;5650.4349565043985;5666.914996275059;5675.517581005578;5676.318841739017;5678.318601767732;5199.844004679866;5403.756830942617;5425.26758887553;5441.2654291670115;5473.397926228112;2977.1217490840254;2984.253936515871;2988.342564635777;2991.446582382223;2996.8501574921165;5865.595106006517;5883.882322353529;5899.852503687356;5901.468867801918;5903.881922361615;5540.972295138579;5757.510611598061;5830.300363956352;5830.416958304136;5852;5956.16613674086;5971.910421343597;5974.372690867396;5989.191459153046;6036.6679832609625;5514.04251490715;5692.203091567101;5698.4016678249845;5703.431556322044;5722.3948912186725;5450.727463626724;5538.317019182199;5647.3505546861;5666.744996475251;5680.659160450221;5831.212786273799;5849.636459035986;5883.235179426647;5888.587798854039;5975.43233392827;4671.532846715301;4716.29255611661;4734.786934588021;4738.8486656373125;4739.336492890929;5437.864053398696;5617.74720137603;5643.915341269802;5662.85842853932;5733;5588.86027849307;5590.944090559043;5599.580031497572;5603.38362780102;5623.718814059363;5361.303030605996;5590.468905454138;5590.692511911884;5615.354234262949;5640.277416904823;5514.36584792754;5530.617345663584;5574.665520068809;5578.944210557843;5592.189132575835;5520.040385663032;5563.805266815642;5614.719264036864;5618.915716264178;5623.634463759842;5212.687238765535;5426.810061647823;5440.633324503853;5456.563474921917;5459.062032978236 09:12:11 INFO - PROCESS | 21124 | |5;yui.html;825.4675734151476;892.732180345896;906.3429013964876;910.1035480052149;957.6600306891054;15090.396384144635;16131.274092665828;16160.838391616095;16166.919165404172;16181.271842767075;16049.277782499787;16859.325626974907;16878.578035549086;16936.23786929588;16954.152292385363;878.4114643189079;884.3102380143498;894.9418287811271;903.3992395057279;913.4610579757941;887.152769105506;887.6937456577471;889.6396959231522;889.8976617688984;890.9109089091104;952.2477242978039;958.3962103874584;961.2790407194605;961.624966263158;961.8461046232604;943.9528023598862;950.8288508068545;952.1097773581723;952.7141857442774;952.8427809411439;697.2225968045593;697.3549466743242;697.6720941157648;698.2877464985686;698.4482259015372;15483.303251353716;15546.144962027083;15581.610459738484;17414.825851741447;17537.64924701499;16726.498205053867;16810.66378672432;16970.40603578869;17072.65854682912;17320.220590073477;13736.381862816097;14010.719785604231;14038.578842634737;14437.927810360987;14918.104913705212;4015.819288132041;4039.151778126597;4054.3918412238104;4168.43726096976;4200.642945349649;55657;57202.713986430215;71658;73421.06315747417;82728;12601.207271496807;12841;12884.484620615258;12927;12974.286414247306;26743.19770406891;26762.464750704876;26778.5983210252;26807.731922680523;26832.59751103735;8928.10718928115;9013.504257265913;9232.676856309998;9311.208547273422;9320.30097742672;4540.931886021713;4612.469565999886;4617.5382461754225;4619.907601848012;4635.3742244797195;3759.736818422739;3861.2856621524547;3877.806109694504;3903.121797595575;3910.6284902934176;652;655.1548502431905;658.8254112660148;661.794843598486;676.8781619308571;822.1860358245414;830.2030051150842;834.5952213176605;848.2620120495209;856.1224744636797;11772.234804737665;12178.330191839534;12226.266424014588;13175.341232938315;13411.798823017663;663.0857381806932;673.4511373230802;678.8879834827198;711.5877176372103;857.4855086947906;5216.626874319457;5452.972735136378;5542.695151766691;5597.104463285855;5601.075822489325;75891.24108758951;76290.61854690692;77059;79277.60361198272;81252;67866.321336786;68342.65828670825;68363.65818170877;68492.657536712;69473;56289;57318;61047.694761525905;61118.38881611216;61915.380846190965;11647.941760291144;12183.208091474025;12270.81593776112;12336;12689.619311420673;41806.79096604497;42147.1570568593;43137.13725725405;44930.77534612437;45768.08463830638;51352;51503;51742;52280.73859630677;54061.72969135129;12660;12683.175593586388;12698.111132220656;12863.549775757803;13061.542846000344;16334.836651633332;16790.496285111465;17505.82494175093;17663.64672706513;17700.734488982933;13848.30758462093;13967.161970281815;14069.155850648584;14245.358958847084;14376.424943002163;15189.16459594733;15242.695146096794;15260.923695381452;15277.77083343773;15345.156016418758;13680;13753.243571603258;13918.164910105426;13955.790663139858;14702.338394772474;10185.341614906833;10884.891151088388;10953.83569246446;11150.442477875911;11172.832407514057;12270.4478298475;12525.488201891385;12764.880450209934;12769.361531923181;12919.870801291867 09:12:11 INFO - PROCESS | 21124 | __end_tp_report 09:12:11 INFO - PROCESS | 21124 | __start_cc_report 09:12:11 INFO - PROCESS | 21124 | _x_x_mozilla_cycle_collect,1360 09:12:11 INFO - PROCESS | 21124 | __end_cc_report 09:12:11 INFO - PROCESS | 21124 | __startTimestamp1492272731084__endTimestamp 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - PROCESS | 21124 | ------- Summary: start ------- 09:12:11 INFO - PROCESS | 21124 | Number of tests: 6 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - PROCESS | 21124 | [#0] dojo.html Cycles:165 Average:5734.75 Median:5998.97 stddev:653.34 (10.9%) stddev-sans-first:628.26 09:12:11 INFO - PROCESS | 21124 | Values: 3361.8 3370.5 3370.7 3389.5 3412.2 5618.1 5623.4 5656.6 5667.7 5708.8 5712.6 5809.3 5820.8 5823.9 5830.7 5248.2 5253.4 5290.2 5296.4 5327.0 5090.7 5096.6 5361.7 5381.5 5390.8 5217.7 5270.8 5306.0 5328.3 5328.7 5353.8 5355.5 5362.2 5365.5 5366.1 4987.3 5208.6 5284.0 5333.0 5339.9 5515.9 5641.6 5703.3 5708.5 5726.1 6143.3 6179.9 6183.8 6193.2 6197.1 6209.3 6222.9 6243.2 6253.5 6254.7 5507.4 5528.1 5720.9 5759.7 5765.1 6310.0 6311.7 6311.8 6312.5 6313.3 6036.7 6090.1 6100.3 6102.1 6106.8 5910.4 5946.8 6119.6 6144.9 6208.9 6142.4 6178.8 6188.9 6203.2 6203.8 5861.7 5897.0 5899.2 6113.6 6206.8 4706.9 4758.1 4765.7 4772.7 4784.7 4108.3 4122.2 4168.8 4222.5 4275.6 5939.4 6017.1 6025.5 6029.0 6037.5 6217.1 6238.3 6243.4 6252.4 6253.8 5734.6 5986.3 5999.0 6007.1 6037.2 5072.6 5103.3 5152.8 5198.7 5212.0 6472.8 6485.6 6493.9 6502.5 6514.3 6188.7 6210.1 6233.1 6253.7 6268.1 6112.3 6177.6 6336.8 6360.3 6450.4 6115.4 6119.1 6121.8 6124.7 6125.2 6228.9 6249.9 6251.2 6251.5 6256.3 5755.0 5871.8 5972.0 6005.1 6069.3 6043.7 6044.8 6058.2 6058.3 6081.6 6024.2 6030.5 6033.6 6048.3 6062.3 5786.2 5805.4 5843.1 6013.2 6068.8 5986.5 5991.3 5995.9 6012.8 6025.5 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - PROCESS | 21124 | [#1] ext.html Cycles:180 Average:45372.96 Median:320617.50 stddev:74687.46 (23.3%) stddev-sans-first:74821.90 09:12:11 INFO - PROCESS | 21124 | Values: 771.2 857.1 871.6 937.9 944.3 30877.5 31247.7 33520.0 42848.1 44213.6 44426.3 44501.0 44628.8 44697.6 44703.8 9258.8 9362.5 9368.4 9387.5 9400.2 7263.1 7484.0 10625.8 10889.1 10892.7 10994.2 11023.0 11029.9 11037.8 11039.8 10935.4 10975.1 10978.8 10987.0 10998.9 10399.1 10449.2 10450.8 10489.3 10710.4 40346.4 40377.2 40490.4 40550.4 40564.0 4167.4 4172.8 4223.6 4261.0 4302.9 28754.0 28985.7 29004.7 29198.3 29436.0 4326.1 4436.7 4453.4 4504.8 4560.3 235689.0 236634.0 237201.7 237605.0 238018.0 52277.0 52548.7 52730.7 52753.5 52833.2 55411.0 55437.4 55460.7 55464.4 55502.4 16958.9 17313.0 17335.6 17343.7 17349.0 13397.7 15187.5 15199.6 15239.5 15296.2 12095.6 12678.8 13067.7 13214.6 13478.1 5534.5 5572.7 5691.2 5697.8 5710.8 1011.2 1043.6 1048.2 1052.9 1087.1 28548.9 28960.3 29064.0 29105.1 29313.4 1105.2 1108.8 1128.2 1152.1 1158.1 14982.8 15290.7 15411.8 15513.4 15592.6 319530.0 320241.0 320994.0 321068.0 321325.0 55877.0 55925.2 56106.0 56171.4 56175.4 1537.6 1551.6 1590.2 1593.3 1597.6 25744.9 27082.0 27172.0 27288.3 27397.6 41339.4 41446.6 41456.8 41534.6 41543.6 293221.0 294290.0 295270.0 295275.0 295916.0 33910.3 34143.8 34214.5 34326.8 34543.0 41772.8 42066.0 43227.0 43270.8 43352.8 32364.8 32605.0 32671.5 32693.3 32976.3 32396.5 32807.0 33144.0 33196.0 33772.7 37938.2 38290.2 38349.0 38490.6 38572.8 31190.4 31529.4 31577.7 31672.2 32138.7 24565.3 25577.4 25607.0 25713.7 26003.2 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - PROCESS | 21124 | [#2] jquery.html Cycles:180 Average:32808.07 Median:22267.61 stddev:89724.79 (402.9%) stddev-sans-first:89943.54 09:12:11 INFO - PROCESS | 21124 | Values: 419.5 452.2 467.4 478.5 479.5 15864.4 18657.3 20013.4 20089.5 20179.9 19788.4 21963.5 22261.7 22273.6 22656.9 6633.0 6720.8 6937.8 6971.9 6977.9 6805.0 6815.9 6864.3 6868.3 6875.9 6760.8 6785.3 7340.4 7640.0 7664.2 7451.2 7589.6 7634.8 7638.0 7647.2 7519.3 7525.5 7535.5 7536.2 7537.3 24121.9 25048.5 25188.7 25529.6 25588.6 3058.3 3064.0 3090.0 3097.6 3140.5 16148.2 16218.4 16242.4 16432.0 16538.5 2194.4 2196.6 2214.5 2269.0 2292.2 108531.0 111647.4 112110.0 112558.4 114481.0 14536.1 18182.3 18407.4 18482.3 18978.2 28899.1 29809.7 29952.8 31793.0 31863.7 10208.2 10422.7 10567.5 10595.4 10745.8 8847.2 8883.6 8912.5 9072.3 9091.9 9023.5 9247.1 9255.2 9365.3 9451.3 3513.5 3552.3 3787.4 3939.3 3978.5 965.4 976.1 977.3 977.5 981.3 21048.4 21061.9 21274.4 21298.3 21399.6 966.4 971.3 982.8 987.9 993.5 10496.9 11368.3 11561.2 11639.2 11658.8 310199.0 311137.0 395044.0 734783.0 747338.0 109367.5 114086.4 114797.0 114955.0 115074.4 1223.0 1237.3 1261.7 1263.6 1264.9 17649.9 17957.9 18202.5 18223.5 18255.4 33194.5 46532.3 50584.5 52667.7 53307.5 63396.7 64162.4 65202.7 65679.7 67004.3 11934.8 12005.0 12032.3 12033.3 12327.1 13205.9 13214.7 13274.7 13418.0 13418.0 11726.6 11830.5 11896.3 12070.9 12243.6 11047.9 11207.4 11307.2 11434.9 11523.0 12112.4 12159.6 12164.7 12222.9 12602.3 11834.9 11932.8 12021.1 12043.6 12303.0 11878.8 12048.3 12132.9 12216.1 12298.4 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - PROCESS | 21124 | [#3] mootools.html Cycles:180 Average:7002.69 Median:2426.05 stddev:4457.08 (183.7%) stddev-sans-first:4450.71 09:12:11 INFO - PROCESS | 21124 | Values: 1543.8 1555.2 1584.0 1588.8 1591.0 10709.5 11478.3 11479.1 11488.5 11498.8 10665.8 10975.1 11369.6 11393.4 11409.2 2441.8 2476.5 2478.6 2480.3 2481.6 2340.6 2371.3 2484.4 2489.6 2490.7 2387.2 2414.8 2431.0 2496.7 2498.1 2270.3 2289.8 2292.2 2295.0 2421.1 2278.5 2307.9 2319.1 2323.9 2325.5 11210.2 11347.1 11362.6 11365.8 11393.8 5701.4 5821.7 5822.4 5831.1 5834.6 3331.9 3405.9 3445.2 3450.8 3468.9 1314.1 1326.4 1331.0 1391.1 1409.6 14857.6 15274.5 17134.9 17426.8 17580.7 4567.0 4663.6 4681.4 4691.2 4701.8 12664.9 12668.8 12670.9 12680.9 12682.9 6020.2 6025.1 6026.0 6028.4 6031.1 5558.3 5559.7 5584.6 5594.4 5643.2 3870.7 3952.3 3982.6 4033.8 4052.6 998.9 1002.1 1016.5 1028.7 1032.6 1030.7 1041.3 1044.9 1054.3 1054.9 6625.4 6843.7 6855.5 6967.5 7003.8 987.3 1017.8 1034.0 1046.0 1053.2 4356.8 4370.3 4393.1 4410.0 4440.2 13807.3 13987.3 14163.4 14289.7 14294.1 14254.6 14505.8 14708.0 14711.2 14728.9 8848.6 10308.0 10528.3 10530.5 10531.4 5836.3 6852.0 6970.2 7171.7 7179.0 12557.4 12590.8 12789.7 12952.2 13093.0 12902.7 12926.7 12998.8 13090.5 13135.8 9217.7 9530.7 9588.8 9594.3 9625.3 10544.4 10581.8 10698.2 10722.2 10774.5 8937.1 8981.7 9126.7 9295.2 9368.3 8914.0 8975.4 8996.1 9002.6 9229.0 8195.8 8310.0 8363.2 8383.9 8401.6 5898.6 5995.6 6007.0 6069.7 6190.3 6151.2 6182.2 6218.7 6368.5 6397.4 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - PROCESS | 21124 | [#4] prototype.html Cycles:180 Average:4915.56 Median:5365.50 stddev:1258.36 (23.5%) stddev-sans-first:1215.66 09:12:11 INFO - PROCESS | 21124 | Values: 413.1 434.8 435.2 442.3 444.9 5326.9 5327.3 5376.9 5404.0 5404.5 5122.8 5294.3 5299.9 5307.8 5342.1 4545.0 4574.0 4592.7 4602.3 4604.8 4568.0 4571.9 4574.3 4636.8 4654.5 4391.8 4636.7 4662.1 4665.0 4668.9 4586.3 4587.8 4590.7 4601.4 4610.0 4456.6 4460.4 4461.4 4477.5 4482.4 4908.5 5267.5 5269.3 5271.6 5295.4 5285.3 5291.6 5306.2 5313.3 5320.3 5501.2 5516.4 5534.4 5557.2 5582.4 4631.9 4795.7 4875.7 4879.8 4929.0 5485.1 5610.7 5632.5 5733.3 5772.3 4981.4 5025.0 5034.8 5037.4 5083.5 5101.2 5177.0 5184.3 5247.7 5259.3 5147.4 5221.2 5246.9 5297.3 5301.5 5498.7 5513.5 5525.4 5529.2 5530.3 1726.9 1796.6 1802.2 1811.6 1811.7 1433.3 1441.7 1454.4 1455.4 1494.7 5355.4 5369.7 5396.4 5427.2 5480.0 5650.4 5666.9 5675.5 5676.3 5678.3 5199.8 5403.8 5425.3 5441.3 5473.4 2977.1 2984.3 2988.3 2991.4 2996.9 5865.6 5883.9 5899.9 5901.5 5903.9 5541.0 5757.5 5830.3 5830.4 5852.0 5956.2 5971.9 5974.4 5989.2 6036.7 5514.0 5692.2 5698.4 5703.4 5722.4 5450.7 5538.3 5647.4 5666.7 5680.7 5831.2 5849.6 5883.2 5888.6 5975.4 4671.5 4716.3 4734.8 4738.8 4739.3 5437.9 5617.7 5643.9 5662.9 5733.0 5588.9 5590.9 5599.6 5603.4 5623.7 5361.3 5590.5 5590.7 5615.4 5640.3 5514.4 5530.6 5574.7 5578.9 5592.2 5520.0 5563.8 5614.7 5618.9 5623.6 5212.7 5426.8 5440.6 5456.6 5459.1 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - PROCESS | 21124 | [#5] yui.html Cycles:180 Average:17946.70 Median:23173.90 stddev:21222.19 (91.6%) stddev-sans-first:21242.78 09:12:11 INFO - PROCESS | 21124 | Values: 825.5 892.7 906.3 910.1 957.7 15090.4 16131.3 16160.8 16166.9 16181.3 16049.3 16859.3 16878.6 16936.2 16954.2 878.4 884.3 894.9 903.4 913.5 887.2 887.7 889.6 889.9 890.9 952.2 958.4 961.3 961.6 961.8 944.0 950.8 952.1 952.7 952.8 697.2 697.4 697.7 698.3 698.4 15483.3 15546.1 15581.6 17414.8 17537.6 16726.5 16810.7 16970.4 17072.7 17320.2 13736.4 14010.7 14038.6 14437.9 14918.1 4015.8 4039.2 4054.4 4168.4 4200.6 55657.0 57202.7 71658.0 73421.1 82728.0 12601.2 12841.0 12884.5 12927.0 12974.3 26743.2 26762.5 26778.6 26807.7 26832.6 8928.1 9013.5 9232.7 9311.2 9320.3 4540.9 4612.5 4617.5 4619.9 4635.4 3759.7 3861.3 3877.8 3903.1 3910.6 652.0 655.2 658.8 661.8 676.9 822.2 830.2 834.6 848.3 856.1 11772.2 12178.3 12226.3 13175.3 13411.8 663.1 673.5 678.9 711.6 857.5 5216.6 5453.0 5542.7 5597.1 5601.1 75891.2 76290.6 77059.0 79277.6 81252.0 67866.3 68342.7 68363.7 68492.7 69473.0 56289.0 57318.0 61047.7 61118.4 61915.4 11647.9 12183.2 12270.8 12336.0 12689.6 41806.8 42147.2 43137.1 44930.8 45768.1 51352.0 51503.0 51742.0 52280.7 54061.7 12660.0 12683.2 12698.1 12863.5 13061.5 16334.8 16790.5 17505.8 17663.6 17700.7 13848.3 13967.2 14069.2 14245.4 14376.4 15189.2 15242.7 15260.9 15277.8 15345.2 13680.0 13753.2 13918.2 13955.8 14702.3 10185.3 10884.9 10953.8 11150.4 11172.8 12270.4 12525.5 12764.9 12769.4 12919.9 09:12:11 INFO - PROCESS | 21124 | -------- Summary: end -------- 09:12:11 INFO - PROCESS | 21124 | 09:12:11 INFO - TEST-INFO | 21124: exit 0 09:12:11 INFO - TEST-OK | dromaeo_css | took 1115240ms 09:12:11 INFO - TEST-START | kraken 09:12:11 INFO - operating with platform_type : linux_ 09:12:11 INFO - Initialising browser for kraken test... 09:12:11 INFO - TEST-INFO | started process 21441 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVmy5hG/profile http://localhost:41558/getInfo.html) 09:12:15 INFO - PROCESS | 21441 | __metrics Screen width/height:1600/1200 09:12:15 INFO - PROCESS | 21441 | colorDepth:24 09:12:15 INFO - PROCESS | 21441 | Browser inner width/height: 1024/697 09:12:15 INFO - PROCESS | 21441 | __metrics 09:12:16 INFO - PROCESS | 21441 | 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" 09:12:16 INFO - PROCESS | 21441 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:17 INFO - TEST-INFO | 21441: exit 0 09:12:17 INFO - Browser initialized. 09:12:17 INFO - Running cycle 1/1 for kraken test... 09:12:17 INFO - TEST-INFO | started process 21530 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVmy5hG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:29 INFO - PROCESS | 21530 | RSS: Main: 140197888 09:12:29 INFO - PROCESS | 21530 | 09:12:31 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41558/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:32 INFO - PROCESS | 21530 | RSS: Main: 183222272 09:12:32 INFO - PROCESS | 21530 | 09:12:35 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41558/tests/kraken/driver.html?testName=audio-dft) 09:12:35 INFO - PROCESS | 21530 | RSS: Main: 174338048 09:12:35 INFO - PROCESS | 21530 | 09:12:38 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41558/tests/kraken/driver.html?testName=audio-fft) 09:12:39 INFO - PROCESS | 21530 | RSS: Main: 265895936 09:12:39 INFO - PROCESS | 21530 | 09:12:41 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41558/tests/kraken/driver.html?testName=audio-oscillator) 09:12:41 INFO - PROCESS | 21530 | RSS: Main: 172261376 09:12:41 INFO - PROCESS | 21530 | 09:12:44 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41558/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:44 INFO - PROCESS | 21530 | RSS: Main: 174833664 09:12:44 INFO - PROCESS | 21530 | 09:12:47 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41558/tests/kraken/driver.html?testName=imaging-darkroom) 09:12:48 INFO - PROCESS | 21530 | RSS: Main: 212852736 09:12:48 INFO - PROCESS | 21530 | 09:12:51 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41558/tests/kraken/driver.html?testName=imaging-desaturate) 09:12:52 INFO - PROCESS | 21530 | RSS: Main: 212287488 09:12:52 INFO - PROCESS | 21530 | 09:12:54 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41558/tests/kraken/driver.html?testName=json-parse-financial) 09:12:55 INFO - PROCESS | 21530 | RSS: Main: 210640896 09:12:55 INFO - PROCESS | 21530 | 09:12:57 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41558/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:12:57 INFO - PROCESS | 21530 | RSS: Main: 173117440 09:12:57 INFO - PROCESS | 21530 | 09:12:59 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:12:59 INFO - PROCESS | 21530 | RSS: Main: 179089408 09:12:59 INFO - PROCESS | 21530 | 09:13:01 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:13:02 INFO - PROCESS | 21530 | RSS: Main: 189530112 09:13:02 INFO - PROCESS | 21530 | 09:13:05 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:05 INFO - PROCESS | 21530 | RSS: Main: 198045696 09:13:05 INFO - PROCESS | 21530 | 09:13:08 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:13:09 INFO - PROCESS | 21530 | RSS: Main: 195428352 09:13:09 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41558/tests/kraken/driver.html?testName=ai-astar) 09:13:10 INFO - PROCESS | 21530 | RSS: Main: 243904512 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | __start_tp_report 09:13:10 INFO - PROCESS | 21530 | _x_x_mozilla_page_load 09:13:10 INFO - PROCESS | 21530 | _x_x_mozilla_page_load_details 09:13:10 INFO - PROCESS | 21530 | |i|pagename|runs| 09:13:10 INFO - PROCESS | 21530 | |0;ai-astar;102;112;113;112;112;113;111;112;113;114 09:13:10 INFO - PROCESS | 21530 | |1;audio-beat-detection;149;119;116;161;116;115;114;113;131;114 09:13:10 INFO - PROCESS | 21530 | |2;audio-dft;163;162;163;160;160;160;160;161;161;160 09:13:10 INFO - PROCESS | 21530 | |3;audio-fft;76;75;76;110;76;76;76;75;107;75 09:13:10 INFO - PROCESS | 21530 | |4;audio-oscillator;82;82;107;82;83;81;97;82;81;94 09:13:10 INFO - PROCESS | 21530 | |5;imaging-gaussian-blur;112;113;113;114;113;112;113;112;114;114 09:13:10 INFO - PROCESS | 21530 | |6;imaging-darkroom;149;147;146;148;149;146;146;148;148;148 09:13:10 INFO - PROCESS | 21530 | |7;imaging-desaturate;94;94;95;94;94;94;94;94;94;93 09:13:10 INFO - PROCESS | 21530 | |8;json-parse-financial;54;54;54;54;55;54;54;55;54;55 09:13:10 INFO - PROCESS | 21530 | |9;json-stringify-tinderbox;53;53;53;53;53;53;53;53;53;52 09:13:10 INFO - PROCESS | 21530 | |10;stanford-crypto-aes;75;79;84;84;75;75;79;75;74;83 09:13:10 INFO - PROCESS | 21530 | |11;stanford-crypto-ccm;121;119;119;120;119;120;127;131;154;119 09:13:10 INFO - PROCESS | 21530 | |12;stanford-crypto-pbkdf2;163;156;153;154;160;155;159;157;159;160 09:13:10 INFO - PROCESS | 21530 | |13;stanford-crypto-sha256-iterative;60;60;62;63;63;63;60;59;58;57 09:13:10 INFO - PROCESS | 21530 | __end_tp_report 09:13:10 INFO - PROCESS | 21530 | __start_cc_report 09:13:10 INFO - PROCESS | 21530 | _x_x_mozilla_cycle_collect,1705 09:13:10 INFO - PROCESS | 21530 | __end_cc_report 09:13:10 INFO - PROCESS | 21530 | __startTimestamp1492272790916__endTimestamp 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | ------- Summary: start ------- 09:13:10 INFO - PROCESS | 21530 | Number of tests: 14 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:3.41 (3.0%) stddev-sans-first:0.88 09:13:10 INFO - PROCESS | 21530 | Values: 102.0 112.0 113.0 112.0 112.0 113.0 111.0 112.0 113.0 114.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:117.50 stddev:16.97 (14.4%) stddev-sans-first:15.58 09:13:10 INFO - PROCESS | 21530 | Values: 149.0 119.0 116.0 161.0 116.0 115.0 114.0 113.0 131.0 114.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.09 09:13:10 INFO - PROCESS | 21530 | Values: 163.0 162.0 163.0 160.0 160.0 160.0 160.0 161.0 161.0 160.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.00 stddev:13.89 (18.3%) stddev-sans-first:14.55 09:13:10 INFO - PROCESS | 21530 | Values: 76.0 75.0 76.0 110.0 76.0 76.0 76.0 75.0 107.0 75.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:82.00 stddev:9.05 (11.0%) stddev-sans-first:9.41 09:13:10 INFO - PROCESS | 21530 | Values: 82.0 82.0 107.0 82.0 83.0 81.0 97.0 82.0 81.0 94.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 09:13:10 INFO - PROCESS | 21530 | Values: 112.0 113.0 113.0 114.0 113.0 112.0 113.0 112.0 114.0 114.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:1.18 (0.8%) stddev-sans-first:1.12 09:13:10 INFO - PROCESS | 21530 | Values: 149.0 147.0 146.0 148.0 149.0 146.0 146.0 148.0 148.0 148.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:13:10 INFO - PROCESS | 21530 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 09:13:10 INFO - PROCESS | 21530 | Values: 54.0 54.0 54.0 54.0 55.0 54.0 54.0 55.0 54.0 55.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#9] json-stringify-tinderbox Cycles:10 Average:52.90 Median:53.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 09:13:10 INFO - PROCESS | 21530 | Values: 53.0 53.0 53.0 53.0 53.0 53.0 53.0 53.0 53.0 52.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:79.00 stddev:4.08 (5.2%) stddev-sans-first:4.15 09:13:10 INFO - PROCESS | 21530 | Values: 75.0 79.0 84.0 84.0 75.0 75.0 79.0 75.0 74.0 83.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:120.50 stddev:11.01 (9.1%) stddev-sans-first:11.59 09:13:10 INFO - PROCESS | 21530 | Values: 121.0 119.0 119.0 120.0 119.0 120.0 127.0 131.0 154.0 119.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.60 Median:159.00 stddev:3.13 (2.0%) stddev-sans-first:2.65 09:13:10 INFO - PROCESS | 21530 | Values: 163.0 156.0 153.0 154.0 160.0 155.0 159.0 157.0 159.0 160.0 09:13:10 INFO - PROCESS | 21530 | 09:13:10 INFO - PROCESS | 21530 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:61.00 stddev:2.17 (3.6%) stddev-sans-first:2.30 09:13:10 INFO - PROCESS | 21530 | Values: 60.0 60.0 62.0 63.0 63.0 63.0 60.0 59.0 58.0 57.0 09:13:10 INFO - PROCESS | 21530 | -------- Summary: end -------- 09:13:10 INFO - PROCESS | 21530 | 09:13:11 INFO - TEST-INFO | 21530: exit 0 09:13:11 INFO - TEST-OK | kraken | took 60115ms 09:13:11 INFO - SUITE-END | took 1175s 09:13:12 INFO - Completed test suite (00:19:35) 09:13:12 INFO - javascript benchmark 09:13:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7837.046119528015, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3361.7646764726464, 3370.528126062351, 3370.679785420384, 3389.508521264418, 3412.1810765416294, 5618.101103823389, 5623.437656234374, 5656.575756818244, 5667.724761928564, 5708.800191993281, 5712.628679135863, 5809.302883653967, 5820.767169313233, 5823.854403639911, 5830.650160990333, 5248.186531087668, 5253.369595648527, 5290.232916227144, 5296.390915044762, 5326.973365133188, 5090.669106508084, 5096.643234973553, 5361.731913404315, 5381.48875856793, 5390.838274851761, 5217.739113044351, 5270.762815673284, 5306.044911915854, 5328.254044433782, 5328.6802791832615, 5353.839384818461, 5355.544778693777, 5362.249285100089, 5365.543928766059, 5366.087765079946, 4987.276844857475, 5208.583313334924, 5283.973580132075, 5332.973335133338, 5339.919901201485, 5515.862103447405, 5641.633293835928, 5703.258576385043, 5708.457696518824, 5726.141078838207, 6143.293521245025, 6179.907301390483, 6183.845403864894, 6193.225846769154, 6197.1324014637985, 6209.285932117819, 6222.875542489125, 6243.1883855099, 6253.499720022387, 6254.718537665816, 5507.394186639466, 5528.087865502187, 5720.942790572124, 5759.712014399263, 5765.135229715576, 6309.968450157811, 6311.684415779285, 6311.810645680637, 6312.495000399957, 6313.305536391085, 6036.728347224385, 6090.116933044684, 6100.328963813977, 6102.145699602059, 6106.755729770848, 5910.408959104141, 5946.821595352147, 6119.632822030692, 6144.893919094517, 6208.906866397007, 6142.437386315926, 6178.783742569079, 6188.938110618837, 6203.193584834033, 6203.751849925952, 5861.677607731615, 5897.0, 5899.174115623816, 6113.6331820090045, 6206.813795586139, 4706.929396059062, 4758.095961767253, 4765.7140571565815, 4772.689775164603, 4784.665073444826, 4108.301588729923, 4122.154958233615, 4168.791560421967, 4222.514410842792, 4275.572442755762, 5939.406059394026, 6017.06735456005, 6025.487833534199, 6029.0, 6037.547183961133, 6217.067439884054, 6238.282597501346, 6243.37566243372, 6252.437280644764, 6253.812385628438, 5734.569907256972, 5986.311574169026, 5998.970005150034, 6007.068904319837, 6037.215162028908, 5072.6195535335, 5103.2855400244, 5152.793888244503, 5198.714070726146, 5211.973940130275, 6472.773452929219, 6485.6108633482145, 6493.870122597522, 6502.544821862424, 6514.250861150934, 6188.690565471798, 6210.099535567227, 6233.096201050913, 6253.6560489173535, 6268.122462855113, 6112.321110680095, 6177.62934223948, 6336.841578960563, 6360.300366959538, 6450.354964503512, 6115.422885572139, 6119.07606531266, 6121.8493044937295, 6124.655575319917, 6125.17440701618, 6228.937710622836, 6249.9375006251175, 6251.156093927261, 6251.499880009497, 6256.343083976299, 5755.0, 5871.7651293947765, 5972.0, 6005.069214271794, 6069.332373439007, 6043.667598281961, 6044.788432404845, 6058.151858739868, 6058.303295121117, 6081.6351018939, 6024.216851809237, 6030.517558595389, 6033.577649564489, 6048.274207095177, 6062.302835174011, 5786.247787787561, 5805.3614102447855, 5843.1235314701435, 6013.248343957005, 6068.787592434243, 5986.521078313637, 5991.2510936132985, 5995.940040599713, 6012.849678757891, 6025.517958563217], "value": 5691.002475309165, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.1671394893516, 857.0572370392568, 871.6034204436979, 937.9296552758541, 944.3248077624497, 30877.536836947464, 31247.68752312476, 33519.994400168005, 42848.14303713924, 44213.557864421346, 44426.333604995954, 44501.0, 44628.77685611568, 44697.553024469744, 44703.77648111755, 9258.814823703538, 9362.531873406337, 9368.391054581465, 9387.5306234688, 9400.200982916425, 7263.0921134858145, 7484.02707648005, 10625.84061239082, 10889.074428673533, 10892.673219803379, 10994.230403871732, 11023.0, 11029.944850275777, 11037.779244415107, 11039.779204415907, 10935.398553079578, 10975.06711929487, 10978.835317470244, 10987.0, 10998.890011099907, 10399.116075133623, 10449.164066874631, 10450.79098418035, 10489.318194317346, 10710.41092739899, 40346.394804077965, 40377.19245615071, 40490.39264411036, 40550.39174412386, 40564.0, 4167.416561681367, 4172.8122234499215, 4223.619874211305, 4260.978695106535, 4302.935455968163, 28754.0, 28985.71014289851, 29004.70995290041, 29198.270043248875, 29435.96974105896, 4326.134773045404, 4436.711613745097, 4453.354263631788, 4504.752238626874, 4560.315952607102, 235689.0, 236634.0, 237201.69794057126, 237605.0, 238018.0, 52277.0, 52548.73725631424, 52730.73634631802, 52753.472465274855, 52833.207501887504, 55411.0, 55437.44562554323, 55460.72269638706, 55464.445355545926, 55502.444975550534, 16958.915205423895, 17313.0, 17335.56661083483, 17343.65312693739, 17349.045802480985, 13397.66505837362, 15187.468438604596, 15199.620009499851, 15239.466618668293, 15296.235188240544, 12095.57665481704, 12678.80981785274, 13067.738645227233, 13214.603561893158, 13478.123921945042, 5534.52956498694, 5572.6625609853345, 5691.231683722727, 5697.800576979786, 5710.828675139753, 1011.1758916483043, 1043.6242952537082, 1048.1587806849252, 1052.85786418834, 1087.0705546757408, 28548.85725571359, 28960.275993100342, 29064.0, 29105.12684619465, 29313.413731725672, 1105.15455676406, 1108.800415925125, 1128.1707944660668, 1152.1358980764426, 1158.0677554568485, 14982.775258371134, 15290.694186116438, 15411.845881541265, 15513.37946482128, 15592.610184745472, 319530.0, 320241.0, 320994.0, 321068.0, 321325.0, 55877.0, 55925.161122583195, 56106.0, 56171.43828561744, 56175.438245617835, 1537.5771662792822, 1551.5578060253, 1590.19695053997, 1593.330801063556, 1597.6085858964627, 25744.8712756435, 27082.0, 27172.0, 27288.317792054564, 27397.58903616487, 41339.379909301984, 41446.58553414427, 41456.79271603622, 41534.58465415307, 41543.58456415397, 293221.0, 294290.0, 295270.0, 295275.0, 295916.0, 33910.3217935635, 34143.82928085344, 34214.48678269778, 34326.82836585801, 34543.0, 41772.79113604413, 42066.0, 43227.0, 43270.78364608157, 43352.78323608362, 32364.838175809913, 32605.0, 32671.509927350635, 32693.346133076728, 32976.34047319088, 32396.514052289705, 32807.0, 33144.0, 33196.0, 33772.66227337794, 37938.24123517459, 38290.234195316494, 38349.0, 38490.615093848704, 38572.80713596414, 31190.37619247557, 31529.36941261208, 31577.684223157474, 31672.208194795316, 32138.678613213568, 24565.263042108767, 25577.360565986, 25607.0, 25713.742862571882, 26003.21990340293], "value": 19009.514260483647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.47984499220973, 452.2154062701214, 467.42273292483776, 478.4569762515887, 479.52526998271713, 15864.444744433948, 18657.253709851608, 20013.399598012064, 20089.497762555944, 20179.899100504514, 19788.40634780959, 21963.450913727123, 22261.66607500889, 22273.55452890941, 22656.88671556644, 6633.0, 6720.764773232938, 6937.826554336131, 6971.930280697205, 6977.930220697779, 6805.013273075401, 6815.863682726343, 6864.313568643146, 6868.2788307227775, 6875.896861547081, 6760.8309792255095, 6785.253622101565, 7340.4127669786285, 7640.0, 7664.233576642347, 7451.180370159278, 7589.582572958485, 7634.847303053935, 7638.045244344485, 7647.235276472364, 7519.323260906544, 7525.4732168748205, 7535.472516923817, 7536.171021187665, 7537.321641052332, 24121.87939060311, 25048.49903001948, 25188.748112518824, 25529.62673451193, 25588.616170757454, 3058.3271680230314, 3064.0, 3090.0, 3097.6282846058616, 3140.5446210299606, 16148.192590370434, 16218.351265949466, 16242.350305987746, 16432.014079155288, 16538.50384488467, 2194.4241991541517, 2196.604611169989, 2214.523877366374, 2269.024319542596, 2292.1748170658548, 108531.0, 111647.44176279148, 112110.0, 112558.43720781426, 114481.0, 14536.112582355358, 18182.27270909149, 18407.447776566725, 18482.260709571467, 18978.240870365305, 28899.133026009255, 29809.701902980694, 29952.801887924237, 31793.0, 31863.681363186533, 10208.234382421348, 10422.739431514121, 10567.471626418648, 10595.364278143335, 10745.78508429827, 8847.24798392144, 8883.644654213758, 8912.465252084896, 9072.319576031825, 9091.909080909238, 9023.458592484472, 9247.12152345526, 9255.167034966886, 9365.250779937725, 9451.33840631163, 3513.4729790531114, 3552.2717842842158, 3787.3561494545993, 3939.330313846653, 3978.4629075074495, 965.358036905464, 976.0819988722053, 977.2670497127154, 977.5405559387077, 981.3326937682444, 21048.368548943556, 21061.89469052645, 21274.36176914695, 21298.25456109029, 21399.572008559742, 966.3525437956586, 971.2763990826738, 982.7739619887466, 987.9012098790064, 993.4734590666958, 10496.947515262527, 11368.317900925971, 11561.248518846416, 11639.185257031926, 11658.825117623242, 310199.0, 311137.0, 395044.0, 734783.0, 747338.0, 109367.45316273368, 114086.42956785164, 114797.0, 114955.0, 115074.424627878, 1222.9510819567117, 1237.2761934268374, 1261.6719652890315, 1263.5767018048873, 1264.9367531623197, 17649.91175044168, 17957.910210449387, 18202.45392638223, 18223.544411389823, 18255.361062362754, 33194.50208246926, 46532.30201547047, 50584.494155057975, 52667.73666131645, 53307.4669253318, 63396.68301658647, 64162.358376417505, 65202.673986629765, 65679.67160164169, 67004.32995670175, 11934.820977685167, 12005.0, 12032.338221397908, 12033.338166400581, 12327.137100403244, 13205.933970330087, 13214.735705285648, 13274.734505309647, 13417.993650476059, 13418.0, 11726.648200553997, 11830.46762895689, 11896.286222826659, 12070.879291206975, 12243.632691019284, 11047.944760276469, 11207.43962801873, 11307.208495405572, 11434.94282528582, 11523.0, 12112.394380281126, 12159.574414895436, 12164.695882603006, 12222.877771222174, 12602.306873122066, 11834.881651183377, 11932.761344772882, 12021.098417618889, 12043.638690839289, 12303.0, 11878.762424751629, 12048.337341446304, 12132.878671213175, 12216.144869859025, 12298.446569904554], "value": 11264.616709298847, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.7915881356014, 1555.152441919154, 1583.9604009899751, 1588.7775711400404, 1590.9761353579697, 10709.46452677366, 11478.311301321926, 11479.081673466113, 11488.540458381654, 11498.770024599513, 10665.78668426631, 10975.06711929485, 11369.602063927765, 11393.430328483584, 11409.20135590509, 2441.7680320369536, 2476.5046990601863, 2478.628205769131, 2480.255923223026, 2481.5781317176125, 2340.625499920013, 2371.2649078785544, 2484.3913241255827, 2489.5643262429094, 2490.663760392342, 2387.236084452976, 2414.782669559739, 2431.0275889644195, 2496.662950501677, 2498.0632262901413, 2270.2508172303183, 2289.839711220215, 2292.243559625328, 2294.9885250573807, 2421.067888862783, 2278.4531712388907, 2307.8730669813153, 2319.095552734435, 2323.8605683659034, 2325.5232677301115, 11210.215284930058, 11347.092232621371, 11362.602308919148, 11365.772684546262, 11393.772124557545, 5701.372848986608, 5821.650700957914, 5822.417758224186, 5831.125331200354, 5834.5624078194305, 3331.916702082443, 3405.9148521287166, 3445.2248244145117, 3450.7929524228375, 3468.9479657805155, 1314.1195399082637, 1326.356716992258, 1330.9733805323935, 1391.08883681189, 1409.5912185466202, 14857.554273371816, 15274.465393711278, 17134.914325428294, 17426.825731742647, 17580.736288955675, 4566.95433045672, 4663.580277775017, 4681.391419115561, 4691.225947718656, 4701.8354357598, 12664.936675316749, 12668.80996785049, 12670.936645316715, 12680.93659531715, 12682.936585317198, 6020.217371741645, 6025.096235564701, 6025.969870150621, 6028.367021462684, 6031.125486804389, 5558.277423934863, 5559.749811258423, 5584.5811564132855, 5594.440555944454, 5643.238162847963, 3870.6903447724117, 3952.26883026641, 3982.6017398260524, 4033.838646454109, 4052.5744796796503, 998.9150922171551, 1002.1331548210745, 1016.4917541229386, 1028.6862506935306, 1032.5766435761302, 1030.7165529479453, 1041.3335465302212, 1044.9477526123662, 1054.3146954479496, 1054.8734151901667, 6625.370589793962, 6843.657817109125, 6855.485838562127, 6967.512274140863, 7003.824904377329, 987.3483500889522, 1017.7506510904727, 1033.9586416543254, 1046.042870773234, 1053.2153545608571, 4356.760378179167, 4370.344448332776, 4393.077453734688, 4410.029793445437, 4440.245158323093, 13807.309634518233, 13987.300634968211, 14163.433462661584, 14289.714205715827, 14294.070885392417, 14254.643633909236, 14505.839532837348, 14708.0, 14711.19088450124, 14728.852711472962, 8848.64605415789, 10308.0, 10528.315659482108, 10530.526126324334, 10531.368117912949, 5836.270466191726, 6852.0, 6970.233274339717, 7171.713131474891, 7179.0, 12557.372131393211, 12590.811137833123, 12789.680257993625, 12952.158109722837, 13093.0, 12902.741945160857, 12926.741465170831, 12998.805017924928, 13090.476380944656, 13135.802962955373, 9217.723468295962, 9530.666426675032, 9588.76028099303, 9594.280428967995, 9625.278104142077, 10544.420056896637, 10581.788364232518, 10698.197635177237, 10722.249442538947, 10774.461276935965, 8937.106289371011, 8981.730548083568, 9126.680566180152, 9295.209907157967, 9368.344215905094, 8913.955430222808, 8975.416597921114, 8996.100389961213, 9002.594883230402, 9228.95385523068, 8195.754127376189, 8309.95845020771, 8363.247307742333, 8383.916160838313, 8401.62192701342, 5898.64608123514, 5995.640261584319, 6007.038873780174, 6069.696515174312, 6190.288116866618, 6151.231096112986, 6182.227221597301, 6218.689065546795, 6368.522360822864, 6397.392247736458], "value": 5310.846779489051, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.0829558380394, 434.80433804787845, 435.2339881808018, 442.250385596414, 444.8665400379887, 5326.946730532693, 5327.307450031501, 5376.919346209805, 5403.999999999995, 5404.54077399102, 5122.795088196477, 5294.338207724035, 5299.920501192476, 5307.787688492465, 5342.076852333157, 4544.954660428112, 4573.977130114345, 4592.74739889306, 4602.332661764042, 4604.769761511928, 4567.977160114211, 4571.948451856075, 4574.336721175428, 4636.81761150906, 4654.511276315989, 4391.802368893392, 4636.675432719711, 4662.0675864827, 4664.976675116636, 4668.883277918046, 4586.3259910132565, 4587.770611469413, 4590.678652494326, 4601.378813860119, 4610.0, 4456.64346852251, 4460.397846290742, 4461.42001539801, 4477.462704475483, 4482.372467854503, 4908.509149085098, 5267.47325267474, 5269.314989051437, 5271.604629652791, 5295.425587728307, 5285.2600635911, 5291.550218231455, 5306.204069389584, 5313.282706834605, 5320.334958130234, 5501.229827824108, 5516.365617953947, 5534.418886016991, 5557.221988921554, 5582.385937546866, 4631.884202894921, 4795.664303498756, 4875.731834749087, 4879.780409881564, 4929.0, 5485.149801780691, 5610.747516361774, 5632.52123569493, 5733.340665823442, 5772.336181339116, 4981.37732783402, 5024.974875125601, 5034.84895453137, 5037.420696619863, 5083.517065878736, 5101.234814777738, 5177.042247184283, 5184.274201611777, 5247.737613119405, 5259.342582177228, 5147.43003383964, 5221.198686453167, 5246.947530524722, 5297.258383826267, 5301.489075613432, 5498.670079795225, 5513.531349835309, 5525.4474552545225, 5529.225908372831, 5530.336359636869, 1726.927686775874, 1796.6316905034437, 1802.2340505285201, 1811.6376724655122, 1811.7101263797992, 1433.2905211920165, 1441.747694153519, 1454.4255019267541, 1455.3887367305756, 1494.7220126791437, 5355.357357117093, 5369.704666243316, 5396.4333744957, 5427.213054107132, 5479.972600136974, 5650.4349565043985, 5666.914996275059, 5675.517581005578, 5676.318841739017, 5678.318601767732, 5199.844004679866, 5403.756830942617, 5425.26758887553, 5441.2654291670115, 5473.397926228112, 2977.1217490840254, 2984.253936515871, 2988.342564635777, 2991.446582382223, 2996.8501574921165, 5865.595106006517, 5883.882322353529, 5899.852503687356, 5901.468867801918, 5903.881922361615, 5540.972295138579, 5757.510611598061, 5830.300363956352, 5830.416958304136, 5852.0, 5956.16613674086, 5971.910421343597, 5974.372690867396, 5989.191459153046, 6036.6679832609625, 5514.04251490715, 5692.203091567101, 5698.4016678249845, 5703.431556322044, 5722.3948912186725, 5450.727463626724, 5538.317019182199, 5647.3505546861, 5666.744996475251, 5680.659160450221, 5831.212786273799, 5849.636459035986, 5883.235179426647, 5888.587798854039, 5975.43233392827, 4671.532846715301, 4716.29255611661, 4734.786934588021, 4738.8486656373125, 4739.336492890929, 5437.864053398696, 5617.74720137603, 5643.915341269802, 5662.85842853932, 5733.0, 5588.86027849307, 5590.944090559043, 5599.580031497572, 5603.38362780102, 5623.718814059363, 5361.303030605996, 5590.468905454138, 5590.692511911884, 5615.354234262949, 5640.277416904823, 5514.36584792754, 5530.617345663584, 5574.665520068809, 5578.944210557843, 5592.189132575835, 5520.040385663032, 5563.805266815642, 5614.719264036864, 5618.915716264178, 5623.634463759842, 5212.687238765535, 5426.810061647823, 5440.633324503853, 5456.563474921917, 5459.062032978236], "value": 4558.471278811362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.4675734151476, 892.732180345896, 906.3429013964876, 910.1035480052149, 957.6600306891054, 15090.396384144635, 16131.274092665828, 16160.838391616095, 16166.919165404172, 16181.271842767075, 16049.277782499787, 16859.325626974907, 16878.578035549086, 16936.23786929588, 16954.152292385363, 878.4114643189079, 884.3102380143498, 894.9418287811271, 903.3992395057279, 913.4610579757941, 887.152769105506, 887.6937456577471, 889.6396959231522, 889.8976617688984, 890.9109089091104, 952.2477242978039, 958.3962103874584, 961.2790407194605, 961.624966263158, 961.8461046232604, 943.9528023598862, 950.8288508068545, 952.1097773581723, 952.7141857442774, 952.8427809411439, 697.2225968045593, 697.3549466743242, 697.6720941157648, 698.2877464985686, 698.4482259015372, 15483.303251353716, 15546.144962027083, 15581.610459738484, 17414.825851741447, 17537.64924701499, 16726.498205053867, 16810.66378672432, 16970.40603578869, 17072.65854682912, 17320.220590073477, 13736.381862816097, 14010.719785604231, 14038.578842634737, 14437.927810360987, 14918.104913705212, 4015.819288132041, 4039.151778126597, 4054.3918412238104, 4168.43726096976, 4200.642945349649, 55657.0, 57202.713986430215, 71658.0, 73421.06315747417, 82728.0, 12601.207271496807, 12841.0, 12884.484620615258, 12927.0, 12974.286414247306, 26743.19770406891, 26762.464750704876, 26778.5983210252, 26807.731922680523, 26832.59751103735, 8928.10718928115, 9013.504257265913, 9232.676856309998, 9311.208547273422, 9320.30097742672, 4540.931886021713, 4612.469565999886, 4617.5382461754225, 4619.907601848012, 4635.3742244797195, 3759.736818422739, 3861.2856621524547, 3877.806109694504, 3903.121797595575, 3910.6284902934176, 652.0, 655.1548502431905, 658.8254112660148, 661.794843598486, 676.8781619308571, 822.1860358245414, 830.2030051150842, 834.5952213176605, 848.2620120495209, 856.1224744636797, 11772.234804737665, 12178.330191839534, 12226.266424014588, 13175.341232938315, 13411.798823017663, 663.0857381806932, 673.4511373230802, 678.8879834827198, 711.5877176372103, 857.4855086947906, 5216.626874319457, 5452.972735136378, 5542.695151766691, 5597.104463285855, 5601.075822489325, 75891.24108758951, 76290.61854690692, 77059.0, 79277.60361198272, 81252.0, 67866.321336786, 68342.65828670825, 68363.65818170877, 68492.657536712, 69473.0, 56289.0, 57318.0, 61047.694761525905, 61118.38881611216, 61915.380846190965, 11647.941760291144, 12183.208091474025, 12270.81593776112, 12336.0, 12689.619311420673, 41806.79096604497, 42147.1570568593, 43137.13725725405, 44930.77534612437, 45768.08463830638, 51352.0, 51503.0, 51742.0, 52280.73859630677, 54061.72969135129, 12660.0, 12683.175593586388, 12698.111132220656, 12863.549775757803, 13061.542846000344, 16334.836651633332, 16790.496285111465, 17505.82494175093, 17663.64672706513, 17700.734488982933, 13848.30758462093, 13967.161970281815, 14069.155850648584, 14245.358958847084, 14376.424943002163, 15189.16459594733, 15242.695146096794, 15260.923695381452, 15277.77083343773, 15345.156016418758, 13680.0, 13753.243571603258, 13918.164910105426, 13955.790663139858, 14702.338394772474, 10185.341614906833, 10884.891151088388, 10953.83569246446, 11150.442477875911, 11172.832407514057, 12270.4478298475, 12525.488201891385, 12764.880450209934, 12769.361531923181, 12919.870801291867], "value": 7852.675392420807, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1449.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 112.0, 112.0, 113.0, 111.0, 112.0, 113.0, 114.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 119.0, 116.0, 161.0, 116.0, 115.0, 114.0, 113.0, 131.0, 114.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 110.0, 76.0, 76.0, 76.0, 75.0, 107.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 107.0, 82.0, 83.0, 81.0, 97.0, 82.0, 81.0, 94.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 114.0, 113.0, 112.0, 113.0, 112.0, 114.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 146.0, 148.0, 149.0, 146.0, 146.0, 148.0, 148.0, 148.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 55.0, 54.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 53.0, 53.0, 53.0, 53.0, 53.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 84.0, 84.0, 75.0, 75.0, 79.0, 75.0, 74.0, 83.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 119.0, 119.0, 120.0, 119.0, 120.0, 127.0, 131.0, 154.0, 119.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 156.0, 153.0, 154.0, 160.0, 155.0, 159.0, 157.0, 159.0, 160.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 62.0, 63.0, 63.0, 63.0, 60.0, 59.0, 58.0, 57.0], "value": 60.5, "unit": "score"}]}]} 09:13:12 INFO - Return code: 0 09:13:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:13:12 ERROR - # TBPL SUCCESS # 09:13:12 INFO - Running post-action listener: _resource_record_post_action 09:13:12 INFO - [mozharness: 2017-04-15 16:13:12.226070Z] Finished run-tests step (success) 09:13:12 INFO - Running post-run listener: _resource_record_post_run 09:13:12 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 274432; Write bytes: 366272512; Read time: 308; Write time: 257672 09:13:12 INFO - TinderboxPrint: CPU usage
13.1% 09:13:12 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 308 09:13:12 INFO - TinderboxPrint: I/O write bytes / time
366,272,512 / 257,672 09:13:12 INFO - TinderboxPrint: CPU idle
8,264.3 (86.9%) 09:13:12 INFO - TinderboxPrint: CPU user
1,201.7 (12.6%) 09:13:12 INFO - TinderboxPrint: Swap in / out
0 / 0 09:13:12 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 109740032; Read time: 84; Write time: 91368 09:13:12 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 254910464; Read time: 212; Write time: 166216 09:13:12 INFO - Running post-run listener: _upload_blobber_files 09:13:12 INFO - Blob upload gear active. 09:13:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:13:12 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 09:13:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:13:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:13:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:13:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:13:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:17 INFO - (blobuploader) - INFO - Done attempting. 09:13:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:13:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:13:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:18 INFO - (blobuploader) - INFO - Done attempting. 09:13:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:13:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:13:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:19 INFO - (blobuploader) - INFO - Done attempting. 09:13:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:13:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:13:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:20 INFO - (blobuploader) - INFO - Done attempting. 09:13:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:13:20 INFO - Return code: 0 09:13:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:13:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:13:20 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/762efb809a60b6fe108af9f97cb5696df593854c4f8b528f7897c0a4bc35c003be2806e12a6970f50aefc1edc640517358fef30b4b0b8d4e3de7b404d2ced45a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5be2b61282fb8f0fe9821424b68174d54604b67718d7ea28f4b6521c9a347cf7aadfd9b3904eca81e48bb39498898942f8299de049bfdd6d87dba087ef69a25d", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bd62b2cfc8d81c52821555a78c4721511db6c90d97c0a203a2bdc20378db615a7949b3fe4bea7c32b663c22a91d35fe0833665f90857e3bf7b1a62c0c6870f48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/70662eb736dce76a1d11047502e8d9d0119ff65f1e49dd8f868b8cf4e1f87b59dc0ab360315776b9add2e2a81ff13115e54ced49f86bad8a8d27be5c95b99c40"} 09:13:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:13:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:13:20 INFO - Contents: 09:13:20 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/762efb809a60b6fe108af9f97cb5696df593854c4f8b528f7897c0a4bc35c003be2806e12a6970f50aefc1edc640517358fef30b4b0b8d4e3de7b404d2ced45a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5be2b61282fb8f0fe9821424b68174d54604b67718d7ea28f4b6521c9a347cf7aadfd9b3904eca81e48bb39498898942f8299de049bfdd6d87dba087ef69a25d", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bd62b2cfc8d81c52821555a78c4721511db6c90d97c0a203a2bdc20378db615a7949b3fe4bea7c32b663c22a91d35fe0833665f90857e3bf7b1a62c0c6870f48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/70662eb736dce76a1d11047502e8d9d0119ff65f1e49dd8f868b8cf4e1f87b59dc0ab360315776b9add2e2a81ff13115e54ced49f86bad8a8d27be5c95b99c40"} 09:13:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:13:20 INFO - Copying logs to upload dir... 09:13:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:13:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.435740 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-15 09:13:20.407808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 09:13:20.411455) ========= 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=03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/762efb809a60b6fe108af9f97cb5696df593854c4f8b528f7897c0a4bc35c003be2806e12a6970f50aefc1edc640517358fef30b4b0b8d4e3de7b404d2ced45a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5be2b61282fb8f0fe9821424b68174d54604b67718d7ea28f4b6521c9a347cf7aadfd9b3904eca81e48bb39498898942f8299de049bfdd6d87dba087ef69a25d", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bd62b2cfc8d81c52821555a78c4721511db6c90d97c0a203a2bdc20378db615a7949b3fe4bea7c32b663c22a91d35fe0833665f90857e3bf7b1a62c0c6870f48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/70662eb736dce76a1d11047502e8d9d0119ff65f1e49dd8f868b8cf4e1f87b59dc0ab360315776b9add2e2a81ff13115e54ced49f86bad8a8d27be5c95b99c40"} build_url:https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/JYhDoRclQjidsZE4fUkjZw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/762efb809a60b6fe108af9f97cb5696df593854c4f8b528f7897c0a4bc35c003be2806e12a6970f50aefc1edc640517358fef30b4b0b8d4e3de7b404d2ced45a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5be2b61282fb8f0fe9821424b68174d54604b67718d7ea28f4b6521c9a347cf7aadfd9b3904eca81e48bb39498898942f8299de049bfdd6d87dba087ef69a25d", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bd62b2cfc8d81c52821555a78c4721511db6c90d97c0a203a2bdc20378db615a7949b3fe4bea7c32b663c22a91d35fe0833665f90857e3bf7b1a62c0c6870f48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/70662eb736dce76a1d11047502e8d9d0119ff65f1e49dd8f868b8cf4e1f87b59dc0ab360315776b9add2e2a81ff13115e54ced49f86bad8a8d27be5c95b99c40"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 09:13:20.440135) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 09:13:20.440450) ========= 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=03627c79f6bb3d1578c4a402000001ba-1492263462.300478-1416049569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004406 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 09:13:20.526872) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 09:13:20.527721) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 09:13:20.528509) ========= ========= Total master_lag: 0.18 =========