builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-081 starttime: 1491408317.47 results: success (0) revision: c549338e76391ddf7725a633bad9dc240ba1ae82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.469802) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.470316) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.470651) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.485600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.485925) ========= 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=483e410453545d07cca6935c000001ab-1491408317.36160-376805410 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-05 09:05:17-- 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% 46.5M=0s 2017-04-05 09:05:17 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121152 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.621630) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.622008) ========= 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=483e410453545d07cca6935c000001ab-1491408317.36160-376805410 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020612 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.671971) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:17.672414) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c549338e76391ddf7725a633bad9dc240ba1ae82 --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 c549338e76391ddf7725a633bad9dc240ba1ae82 --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=483e410453545d07cca6935c000001ab-1491408317.36160-376805410 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-05 09:05:18,062 truncating revision to first 12 chars 2017-04-05 09:05:18,062 Setting DEBUG logging. 2017-04-05 09:05:18,062 attempt 1/10 2017-04-05 09:05:18,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c549338e7639?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-05 09:05:18,506 unpacking tar archive at: mozilla-aurora-c549338e7639/testing/mozharness/ program finished with exit code 0 elapsedTime=0.795397 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:18.482185) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:18.482547) ========= script_repo_revision: c549338e76391ddf7725a633bad9dc240ba1ae82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:18.483042) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:18.483340) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 09:05:18.494555) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-05 09:05:18.495057) ========= /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=483e410453545d07cca6935c000001ab-1491408317.36160-376805410 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:05:18 INFO - MultiFileLogger online at 20170405 09:05:18 in /builds/slave/test 09:05:18 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 09:05:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:05:18 INFO - {'append_to_log': False, 09:05:18 INFO - 'base_work_dir': '/builds/slave/test', 09:05:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 09:05:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:05:18 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 09:05:18 INFO - 'buildbot_json_path': 'buildprops.json', 09:05:18 INFO - 'config_files': ('talos/linux_config.py',), 09:05:18 INFO - 'default_actions': ('clobber', 09:05:18 INFO - 'read-buildbot-config', 09:05:18 INFO - 'download-and-extract', 09:05:18 INFO - 'populate-webroot', 09:05:18 INFO - 'create-virtualenv', 09:05:18 INFO - 'install', 09:05:18 INFO - 'run-tests'), 09:05:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:05:18 INFO - 'download_minidump_stackwalk': True, 09:05:18 INFO - 'download_symbols': 'ondemand', 09:05:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:05:18 INFO - 'tooltool.py': '/tools/tooltool.py', 09:05:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:05:18 INFO - '/tools/misc-python/virtualenv.py')}, 09:05:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:05:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:05:18 INFO - 'gecko_profile': False, 09:05:18 INFO - 'gecko_profile_interval': 0, 09:05:18 INFO - 'installer_path': 'installer.exe', 09:05:18 INFO - 'log_level': 'info', 09:05:18 INFO - 'log_name': 'talos', 09:05:18 INFO - 'log_to_console': True, 09:05:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:05:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:05:18 INFO - 'opt_config_files': (), 09:05:18 INFO - 'pip_index': False, 09:05:18 INFO - 'suite': 'dromaeojs', 09:05:18 INFO - 'system_bits': '32', 09:05:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:05:18 INFO - 'title': 'talos-linux64-ix-081', 09:05:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:05:18 INFO - 'use_talos_json': True, 09:05:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:05:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:05:18 INFO - 'work_dir': 'build'} 09:05:18 INFO - [mozharness: 2017-04-05 16:05:18.987628Z] Running clobber step. 09:05:18 INFO - Running pre-action listener: _resource_record_pre_action 09:05:18 INFO - Running main action method: clobber 09:05:18 INFO - rmtree: /builds/slave/test/build 09:05:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:05:19 INFO - Running post-action listener: _resource_record_post_action 09:05:19 INFO - [mozharness: 2017-04-05 16:05:19.916270Z] Finished clobber step (success) 09:05:19 INFO - [mozharness: 2017-04-05 16:05:19.916370Z] Running read-buildbot-config step. 09:05:19 INFO - Running pre-action listener: _resource_record_pre_action 09:05:19 INFO - Running main action method: read_buildbot_config 09:05:19 INFO - Using buildbot properties: 09:05:19 INFO - { 09:05:19 INFO - "project": "", 09:05:19 INFO - "product": "firefox", 09:05:19 INFO - "who": "ffxbld@noreply.mozilla.org", 09:05:19 INFO - "installer_path": "public/build/target.tar.bz2", 09:05:19 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:05:19 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 09:05:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 09:05:19 INFO - "stage_platform": "linux64", 09:05:19 INFO - "basedir": "/builds/slave/test", 09:05:19 INFO - "buildnumber": 117, 09:05:19 INFO - "platform": "ubuntu64_hw", 09:05:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:05:19 INFO - "slavebuilddir": "test", 09:05:19 INFO - "taskId": "UQu-v7-4RmqXyD3u68d4tA", 09:05:19 INFO - "branch": "mozilla-aurora", 09:05:19 INFO - "script_repo_revision": "production", 09:05:19 INFO - "revision": "c549338e76391ddf7725a633bad9dc240ba1ae82", 09:05:19 INFO - "slavename": "talos-linux64-ix-081", 09:05:19 INFO - "repo_path": "releases/mozilla-aurora" 09:05:19 INFO - } 09:05:19 INFO - Finding installer, test and symbols from parent task. 09:05:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQu-v7-4RmqXyD3u68d4tA'}, attempt #1 09:05:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQu-v7-4RmqXyD3u68d4tA'}, attempt #1 09:05:20 INFO - Task dependencies: OL7NM3ZiTnOF_IJKygVZAQ 09:05:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ'}, attempt #1 09:05:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.tar.bz2 09:05:21 INFO - Set test_url: https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/test_packages.json 09:05:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.crashreporter-symbols.zip 09:05:21 INFO - Running post-action listener: _resource_record_post_action 09:05:21 INFO - [mozharness: 2017-04-05 16:05:21.307630Z] Finished read-buildbot-config step (success) 09:05:21 INFO - [mozharness: 2017-04-05 16:05:21.307851Z] Running download-and-extract step. 09:05:21 INFO - Running pre-action listener: _resource_record_pre_action 09:05:21 INFO - Running main action method: download_and_extract 09:05:21 INFO - mkdir: /builds/slave/test/build/tests 09:05:21 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')]} 09:05:21 INFO - trying https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.test_packages.json 09:05:21 INFO - Downloading https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:05:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:05:21 INFO - Downloaded 896 bytes. 09:05:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:05:21 INFO - Using the following test package requirements: 09:05:21 INFO - {u'common': [u'target.common.tests.zip'], 09:05:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:05:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:05:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:05:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:05:21 INFO - u'mozbase': [u'target.common.tests.zip'], 09:05:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:05:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:05:21 INFO - u'web-platform': [u'target.common.tests.zip', 09:05:21 INFO - u'target.web-platform.tests.zip'], 09:05:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:05:21 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:05:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.common.tests.zip 09:05:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:05:21 INFO - Fetch https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.common.tests.zip into memory 09:05:22 INFO - Expected file size: 59542339 09:05:22 INFO - Obtained file size: 59542339 09:05:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:05:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.common.tests.zip 09:05:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:05:32 INFO - Fetch https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.common.tests.zip into memory 09:05:33 INFO - Expected file size: 59542339 09:05:33 INFO - Obtained file size: 59542339 09:05:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.talos.tests.zip 09:05:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:05:44 INFO - Fetch https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.talos.tests.zip into memory 09:05:45 INFO - Expected file size: 14404438 09:05:45 INFO - Obtained file size: 14404438 09:05:45 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')]} 09:05:45 INFO - trying https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.tar.bz2 09:05:45 INFO - Downloading https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:05:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:05:46 INFO - Downloaded 66781539 bytes. 09:05:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.tar.bz2 09:05:46 INFO - mkdir: /builds/slave/test/properties 09:05:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:05:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:05:46 INFO - Contents: 09:05:46 INFO - build_url:https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.tar.bz2 09:05:46 INFO - Running post-action listener: _resource_record_post_action 09:05:46 INFO - Running post-action listener: set_extra_try_arguments 09:05:46 INFO - [mozharness: 2017-04-05 16:05:46.477290Z] Finished download-and-extract step (success) 09:05:46 INFO - [mozharness: 2017-04-05 16:05:46.477386Z] Running populate-webroot step. 09:05:46 INFO - Running pre-action listener: _resource_record_pre_action 09:05:46 INFO - Running main action method: populate_webroot 09:05:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:05:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:05:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:05:46 INFO - '--tppagecycles', 09:05:46 INFO - '7'], 09:05:46 INFO - 'tests': ['tsvgm']}}, 09:05:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:05:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:05:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:05:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:05:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:05:46 INFO - 'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:05:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:05:46 INFO - 'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['damp', 'tps']}, 09:05:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:05:46 INFO - 'tests': ['damp', 'tps']}, 09:05:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['dromaeo_dom']}, 09:05:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:05:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['basic_compositor_video']}, 09:05:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:05:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['a11yr', 09:05:46 INFO - 'ts_paint', 09:05:46 INFO - 'tpaint', 09:05:46 INFO - 'sessionrestore', 09:05:46 INFO - 'sessionrestore_no_auto_restore', 09:05:46 INFO - 'tabpaint']}, 09:05:46 INFO - 'other-e10s': {'tests': ['a11yr', 09:05:46 INFO - 'ts_paint', 09:05:46 INFO - 'tpaint', 09:05:46 INFO - 'sessionrestore', 09:05:46 INFO - 'sessionrestore_no_auto_restore', 09:05:46 INFO - 'tabpaint']}, 09:05:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:05:46 INFO - 'ts_paint', 09:05:46 INFO - 'tpaint', 09:05:46 INFO - 'sessionrestore', 09:05:46 INFO - 'sessionrestore_no_auto_restore', 09:05:46 INFO - 'tabpaint']}, 09:05:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:05:46 INFO - 'ts_paint', 09:05:46 INFO - 'tpaint', 09:05:46 INFO - 'sessionrestore', 09:05:46 INFO - 'sessionrestore_no_auto_restore', 09:05:46 INFO - 'tabpaint']}, 09:05:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['a11yr', 09:05:46 INFO - 'ts_paint', 09:05:46 INFO - 'tpaint', 09:05:46 INFO - 'sessionrestore', 09:05:46 INFO - 'sessionrestore_no_auto_restore', 09:05:46 INFO - 'tabpaint']}, 09:05:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['a11yr', 09:05:46 INFO - 'ts_paint', 09:05:46 INFO - 'tpaint', 09:05:46 INFO - 'sessionrestore', 09:05:46 INFO - 'sessionrestore_no_auto_restore', 09:05:46 INFO - 'tabpaint']}, 09:05:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['tsvgx', 09:05:46 INFO - 'tsvgr_opacity', 09:05:46 INFO - 'tart', 09:05:46 INFO - 'tscrollx', 09:05:46 INFO - 'cart', 09:05:46 INFO - 'tsvg_static']}, 09:05:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:05:46 INFO - 'tsvgr_opacity', 09:05:46 INFO - 'tart', 09:05:46 INFO - 'tscrollx', 09:05:46 INFO - 'cart', 09:05:46 INFO - 'tsvg_static']}, 09:05:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:05:46 INFO - 'talos_options': ['--disable-e10s'], 09:05:46 INFO - 'tests': ['tp5o']}, 09:05:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:05:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:05:46 INFO - 'talos_options': ['--disable-e10s', 09:05:46 INFO - '--xperf_path', 09:05:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:46 INFO - 'tests': ['tp5n']}, 09:05:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:05:46 INFO - 'talos_options': ['--xperf_path', 09:05:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:46 INFO - 'tests': ['tp5n']}}, 09:05:46 INFO - 'talos.zip': {'path': '', 09:05:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:05:46 INFO - Running post-action listener: _resource_record_post_action 09:05:46 INFO - [mozharness: 2017-04-05 16:05:46.490443Z] Finished populate-webroot step (success) 09:05:46 INFO - [mozharness: 2017-04-05 16:05:46.490533Z] Running create-virtualenv step. 09:05:46 INFO - Running pre-action listener: _resource_record_pre_action 09:05:46 INFO - Running main action method: create_virtualenv 09:05:46 INFO - Creating virtualenv /builds/slave/test/build/venv 09:05:46 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 09:05:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:05:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:05:46 INFO - Using real prefix '/usr' 09:05:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:05:47 INFO - Installing distribute.............................................................................................................................................................................................done. 09:05:49 INFO - Installing pip.................done. 09:05:49 INFO - Return code: 0 09:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:05:49 INFO - Reading from file tmpfile_stdout 09:05:49 INFO - Output received: 09:05:49 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) 09:05:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:05:49 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')]} 09:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:49 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')]} 09:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:49 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 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:05:49 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 09:05:49 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 09:05:49 INFO - Using env: {'DISPLAY': ':0', 09:05:49 INFO - 'HOME': '/home/cltbld', 09:05:49 INFO - 'LANG': 'en_US.UTF-8', 09:05:49 INFO - 'LANGUAGE': 'en_US:en', 09:05:49 INFO - 'LOGNAME': 'cltbld', 09:05:49 INFO - 'MAIL': '/var/mail/cltbld', 09:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:49 INFO - 'MOZ_NO_REMOTE': '1', 09:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:49 INFO - 'NO_EM_RESTART': '1', 09:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:49 INFO - 'PWD': '/builds/slave/test', 09:05:49 INFO - 'SHELL': '/bin/bash', 09:05:49 INFO - 'SHLVL': '1', 09:05:49 INFO - 'TERM': 'linux', 09:05:49 INFO - 'TMOUT': '86400', 09:05:49 INFO - 'USER': 'cltbld', 09:05:49 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:49 INFO - '_': '/tools/buildbot/bin/python'} 09:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:49 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 09:05:49 INFO - Cleaning up... 09:05:49 INFO - Return code: 0 09:05:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:05:49 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')]} 09:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:49 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')]} 09:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:49 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 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:05:49 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 09:05:49 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 09:05:49 INFO - Using env: {'DISPLAY': ':0', 09:05:49 INFO - 'HOME': '/home/cltbld', 09:05:49 INFO - 'LANG': 'en_US.UTF-8', 09:05:49 INFO - 'LANGUAGE': 'en_US:en', 09:05:49 INFO - 'LOGNAME': 'cltbld', 09:05:49 INFO - 'MAIL': '/var/mail/cltbld', 09:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:49 INFO - 'MOZ_NO_REMOTE': '1', 09:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:49 INFO - 'NO_EM_RESTART': '1', 09:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:49 INFO - 'PWD': '/builds/slave/test', 09:05:49 INFO - 'SHELL': '/bin/bash', 09:05:49 INFO - 'SHLVL': '1', 09:05:49 INFO - 'TERM': 'linux', 09:05:49 INFO - 'TMOUT': '86400', 09:05:49 INFO - 'USER': 'cltbld', 09:05:49 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:49 INFO - '_': '/tools/buildbot/bin/python'} 09:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:49 INFO - Downloading/unpacking psutil>=3.1.1 09:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:05:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:51 INFO - Installing collected packages: psutil 09:05:51 INFO - Running setup.py install for psutil 09:05:51 INFO - building 'psutil._psutil_linux' extension 09:05:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:05:52 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 09:05:52 INFO - building 'psutil._psutil_posix' extension 09:05:52 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 09:05:52 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 09:05:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:52 INFO - Successfully installed psutil 09:05:52 INFO - Cleaning up... 09:05:52 INFO - Return code: 0 09:05:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:05:52 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')]} 09:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:52 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')]} 09:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:52 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 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:05:52 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 09:05:52 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 09:05:52 INFO - Using env: {'DISPLAY': ':0', 09:05:52 INFO - 'HOME': '/home/cltbld', 09:05:52 INFO - 'LANG': 'en_US.UTF-8', 09:05:52 INFO - 'LANGUAGE': 'en_US:en', 09:05:52 INFO - 'LOGNAME': 'cltbld', 09:05:52 INFO - 'MAIL': '/var/mail/cltbld', 09:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:52 INFO - 'MOZ_NO_REMOTE': '1', 09:05:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:52 INFO - 'NO_EM_RESTART': '1', 09:05:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:52 INFO - 'PWD': '/builds/slave/test', 09:05:52 INFO - 'SHELL': '/bin/bash', 09:05:52 INFO - 'SHLVL': '1', 09:05:52 INFO - 'TERM': 'linux', 09:05:52 INFO - 'TMOUT': '86400', 09:05:52 INFO - 'USER': 'cltbld', 09:05:52 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:52 INFO - '_': '/tools/buildbot/bin/python'} 09:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:05:52 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 09:05:52 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 09:05:52 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 09:05:52 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 09:05:52 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 09:05:52 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 09:05:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:05:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:05:54 INFO - Installing collected packages: mozsystemmonitor 09:05:54 INFO - Running setup.py install for mozsystemmonitor 09:05:54 INFO - Successfully installed mozsystemmonitor 09:05:54 INFO - Cleaning up... 09:05:54 INFO - Return code: 0 09:05:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:05:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:05:54 INFO - Using env: {'DISPLAY': ':0', 09:05:54 INFO - 'HOME': '/home/cltbld', 09:05:54 INFO - 'LANG': 'en_US.UTF-8', 09:05:54 INFO - 'LANGUAGE': 'en_US:en', 09:05:54 INFO - 'LOGNAME': 'cltbld', 09:05:54 INFO - 'MAIL': '/var/mail/cltbld', 09:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:54 INFO - 'MOZ_NO_REMOTE': '1', 09:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:54 INFO - 'NO_EM_RESTART': '1', 09:05:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:54 INFO - 'PWD': '/builds/slave/test', 09:05:54 INFO - 'SHELL': '/bin/bash', 09:05:54 INFO - 'SHLVL': '1', 09:05:54 INFO - 'TERM': 'linux', 09:05:54 INFO - 'TMOUT': '86400', 09:05:54 INFO - 'USER': 'cltbld', 09:05:54 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:54 INFO - '_': '/tools/buildbot/bin/python'} 09:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:55 INFO - Downloading/unpacking jsonschema==2.5.1 09:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:05:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:57 INFO - Downloading functools32-3.2.3-2.tar.gz 09:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:05:57 INFO - warning: no files found matching '*.txt' 09:05:57 INFO - no previously-included directories found matching 'build' 09:05:57 INFO - no previously-included directories found matching 'dist' 09:05:57 INFO - no previously-included directories found matching '.git*' 09:05:57 INFO - Installing collected packages: jsonschema, functools32 09:05:57 INFO - Running setup.py install for functools32 09:05:57 INFO - warning: no files found matching '*.txt' 09:05:57 INFO - no previously-included directories found matching 'build' 09:05:57 INFO - no previously-included directories found matching 'dist' 09:05:57 INFO - no previously-included directories found matching '.git*' 09:05:57 INFO - Successfully installed jsonschema functools32 09:05:57 INFO - Cleaning up... 09:05:57 INFO - Return code: 0 09:05:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:05:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:05:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:05:57 INFO - Using env: {'DISPLAY': ':0', 09:05:57 INFO - 'HOME': '/home/cltbld', 09:05:57 INFO - 'LANG': 'en_US.UTF-8', 09:05:57 INFO - 'LANGUAGE': 'en_US:en', 09:05:57 INFO - 'LOGNAME': 'cltbld', 09:05:57 INFO - 'MAIL': '/var/mail/cltbld', 09:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:57 INFO - 'MOZ_NO_REMOTE': '1', 09:05:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:57 INFO - 'NO_EM_RESTART': '1', 09:05:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:57 INFO - 'PWD': '/builds/slave/test', 09:05:57 INFO - 'SHELL': '/bin/bash', 09:05:57 INFO - 'SHLVL': '1', 09:05:57 INFO - 'TERM': 'linux', 09:05:57 INFO - 'TMOUT': '86400', 09:05:57 INFO - 'USER': 'cltbld', 09:05:57 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:57 INFO - '_': '/tools/buildbot/bin/python'} 09:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:05:57 INFO - Cleaning up... 09:05:57 INFO - Return code: 0 09:05:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:05:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:05:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:05:57 INFO - Using env: {'DISPLAY': ':0', 09:05:57 INFO - 'HOME': '/home/cltbld', 09:05:57 INFO - 'LANG': 'en_US.UTF-8', 09:05:57 INFO - 'LANGUAGE': 'en_US:en', 09:05:57 INFO - 'LOGNAME': 'cltbld', 09:05:57 INFO - 'MAIL': '/var/mail/cltbld', 09:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:57 INFO - 'MOZ_NO_REMOTE': '1', 09:05:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:57 INFO - 'NO_EM_RESTART': '1', 09:05:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:57 INFO - 'PWD': '/builds/slave/test', 09:05:57 INFO - 'SHELL': '/bin/bash', 09:05:57 INFO - 'SHLVL': '1', 09:05:57 INFO - 'TERM': 'linux', 09:05:57 INFO - 'TMOUT': '86400', 09:05:57 INFO - 'USER': 'cltbld', 09:05:57 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:57 INFO - '_': '/tools/buildbot/bin/python'} 09:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:59 INFO - Downloading blobuploader-1.2.4.tar.gz 09:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:06:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:06:00 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 09:06:00 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 09:06:00 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 09:06:00 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 09:06:00 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 09:06:00 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 09:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:06:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:06:00 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 09:06:00 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 09:06:00 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 09:06:00 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 09:06:00 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 09:06:00 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 09:06:00 INFO - Downloading docopt-0.6.1.tar.gz 09:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:06:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:06:00 INFO - Running setup.py install for blobuploader 09:06:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:06:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:06:01 INFO - Running setup.py install for requests 09:06:01 INFO - Running setup.py install for docopt 09:06:01 INFO - Successfully installed blobuploader requests docopt 09:06:01 INFO - Cleaning up... 09:06:01 INFO - Return code: 0 09:06:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:01 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')]} 09:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:01 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')]} 09:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:01 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 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:06:01 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 09:06:01 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 09:06:01 INFO - Using env: {'DISPLAY': ':0', 09:06:01 INFO - 'HOME': '/home/cltbld', 09:06:01 INFO - 'LANG': 'en_US.UTF-8', 09:06:01 INFO - 'LANGUAGE': 'en_US:en', 09:06:01 INFO - 'LOGNAME': 'cltbld', 09:06:01 INFO - 'MAIL': '/var/mail/cltbld', 09:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:01 INFO - 'MOZ_NO_REMOTE': '1', 09:06:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:01 INFO - 'NO_EM_RESTART': '1', 09:06:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:01 INFO - 'PWD': '/builds/slave/test', 09:06:01 INFO - 'SHELL': '/bin/bash', 09:06:01 INFO - 'SHLVL': '1', 09:06:01 INFO - 'TERM': 'linux', 09:06:01 INFO - 'TMOUT': '86400', 09:06:01 INFO - 'USER': 'cltbld', 09:06:01 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:01 INFO - '_': '/tools/buildbot/bin/python'} 09:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:01 INFO - Running setup.py (path:/tmp/pip-zcy8Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:01 INFO - Running setup.py (path:/tmp/pip-TgXmHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:01 INFO - Running setup.py (path:/tmp/pip-U64FY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:02 INFO - Running setup.py (path:/tmp/pip-wTTg_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:02 INFO - Running setup.py (path:/tmp/pip-34C0RB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:02 INFO - Running setup.py (path:/tmp/pip-dJldJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:02 INFO - Running setup.py (path:/tmp/pip-uJNXQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:02 INFO - Running setup.py (path:/tmp/pip-ksh4j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:02 INFO - Running setup.py (path:/tmp/pip-csymo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:02 INFO - Running setup.py (path:/tmp/pip-GtKQWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:02 INFO - Running setup.py (path:/tmp/pip-Tic2KJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:02 INFO - Running setup.py (path:/tmp/pip-4qHR0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:02 INFO - Running setup.py (path:/tmp/pip-la9baa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:02 INFO - Running setup.py (path:/tmp/pip-C15_7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:02 INFO - Running setup.py (path:/tmp/pip-Lz_CHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:02 INFO - Running setup.py (path:/tmp/pip-1wMeip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:02 INFO - Running setup.py (path:/tmp/pip-gWGyE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:06:03 INFO - Running setup.py install for manifestparser 09:06:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Running setup.py install for mozcrash 09:06:03 INFO - Running setup.py install for mozdebug 09:06:03 INFO - Running setup.py install for mozdevice 09:06:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Running setup.py install for mozfile 09:06:03 INFO - Running setup.py install for mozhttpd 09:06:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Running setup.py install for mozinfo 09:06:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Running setup.py install for mozInstall 09:06:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:06:03 INFO - Running setup.py install for mozleak 09:06:03 INFO - Running setup.py install for mozlog 09:06:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:06:04 INFO - Running setup.py install for moznetwork 09:06:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:06:04 INFO - Running setup.py install for mozprocess 09:06:04 INFO - Running setup.py install for mozprofile 09:06:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:06:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:06:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:06:04 INFO - Running setup.py install for mozrunner 09:06:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:06:04 INFO - Running setup.py install for mozscreenshot 09:06:04 INFO - Running setup.py install for moztest 09:06:04 INFO - Running setup.py install for mozversion 09:06:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:06:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:06:04 INFO - Cleaning up... 09:06:04 INFO - Return code: 0 09:06:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06: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')]} 09:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:04 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 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:06:04 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 09:06:04 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 09:06:04 INFO - Using env: {'DISPLAY': ':0', 09:06:04 INFO - 'HOME': '/home/cltbld', 09:06:04 INFO - 'LANG': 'en_US.UTF-8', 09:06:04 INFO - 'LANGUAGE': 'en_US:en', 09:06:04 INFO - 'LOGNAME': 'cltbld', 09:06:04 INFO - 'MAIL': '/var/mail/cltbld', 09:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:04 INFO - 'MOZ_NO_REMOTE': '1', 09:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:04 INFO - 'NO_EM_RESTART': '1', 09:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:04 INFO - 'PWD': '/builds/slave/test', 09:06:04 INFO - 'SHELL': '/bin/bash', 09:06:04 INFO - 'SHLVL': '1', 09:06:04 INFO - 'TERM': 'linux', 09:06:04 INFO - 'TMOUT': '86400', 09:06:04 INFO - 'USER': 'cltbld', 09:06:04 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:04 INFO - '_': '/tools/buildbot/bin/python'} 09:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:05 INFO - Running setup.py (path:/tmp/pip-m4wVeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:05 INFO - Running setup.py (path:/tmp/pip-WPZhfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:05 INFO - Running setup.py (path:/tmp/pip-IvmxFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:05 INFO - Running setup.py (path:/tmp/pip-okUjg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:05 INFO - Running setup.py (path:/tmp/pip-BaCtKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:05 INFO - Running setup.py (path:/tmp/pip-HICX9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:05 INFO - Running setup.py (path:/tmp/pip-sONtQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:05 INFO - Running setup.py (path:/tmp/pip-JbStDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:05 INFO - Running setup.py (path:/tmp/pip-qo7rA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:05 INFO - Running setup.py (path:/tmp/pip-9WkJBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:05 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)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:05 INFO - Running setup.py (path:/tmp/pip-1_JJ7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:05 INFO - Running setup.py (path:/tmp/pip-aVbKg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:05 INFO - Running setup.py (path:/tmp/pip-d1eelL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:06 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)) 09:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:06 INFO - Running setup.py (path:/tmp/pip-UWgBQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:06 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)) 09:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:06 INFO - Running setup.py (path:/tmp/pip-lIyJhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:06 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)) 09:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:06 INFO - Running setup.py (path:/tmp/pip-wbTzaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:06 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)) 09:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:06 INFO - Running setup.py (path:/tmp/pip-ddkR_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:06 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)) 09:06:06 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)) 09:06:06 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)) 09:06:06 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)) 09:06:06 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)) 09:06:06 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)) 09:06:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:08 INFO - Downloading blessings-1.6.tar.gz 09:06:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:06:08 INFO - Installing collected packages: blessings 09:06:08 INFO - Running setup.py install for blessings 09:06:08 INFO - Successfully installed blessings 09:06:08 INFO - Cleaning up... 09:06:08 INFO - Return code: 0 09:06:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:08 INFO - Reading from file tmpfile_stdout 09:06:08 INFO - Current package versions: 09:06:08 INFO - argparse == 1.2.1 09:06:08 INFO - blessings == 1.6 09:06:08 INFO - blobuploader == 1.2.4 09:06:08 INFO - docopt == 0.6.1 09:06:08 INFO - functools32 == 3.2.3-2 09:06:08 INFO - jsonschema == 2.5.1 09:06:08 INFO - manifestparser == 1.1 09:06:08 INFO - mozInstall == 1.12 09:06:08 INFO - mozcrash == 1.0 09:06:08 INFO - mozdebug == 0.1 09:06:08 INFO - mozdevice == 0.49 09:06:08 INFO - mozfile == 1.2 09:06:08 INFO - mozhttpd == 0.7 09:06:08 INFO - mozinfo == 0.9 09:06:08 INFO - mozleak == 0.1 09:06:08 INFO - mozlog == 3.4 09:06:08 INFO - moznetwork == 0.27 09:06:08 INFO - mozprocess == 0.25 09:06:08 INFO - mozprofile == 0.28 09:06:08 INFO - mozrunner == 6.13 09:06:08 INFO - mozscreenshot == 0.1 09:06:08 INFO - mozsystemmonitor == 0.3 09:06:08 INFO - moztest == 0.8 09:06:08 INFO - mozversion == 1.4 09:06:08 INFO - psutil == 3.1.1 09:06:08 INFO - requests == 1.2.3 09:06:08 INFO - wsgiref == 0.1.2 09:06:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:08 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 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:06:08 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 09:06:08 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 09:06:08 INFO - Using env: {'DISPLAY': ':0', 09:06:08 INFO - 'HOME': '/home/cltbld', 09:06:08 INFO - 'LANG': 'en_US.UTF-8', 09:06:08 INFO - 'LANGUAGE': 'en_US:en', 09:06:08 INFO - 'LOGNAME': 'cltbld', 09:06:08 INFO - 'MAIL': '/var/mail/cltbld', 09:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:08 INFO - 'MOZ_NO_REMOTE': '1', 09:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:08 INFO - 'NO_EM_RESTART': '1', 09:06:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:08 INFO - 'PWD': '/builds/slave/test', 09:06:08 INFO - 'SHELL': '/bin/bash', 09:06:08 INFO - 'SHLVL': '1', 09:06:08 INFO - 'TERM': 'linux', 09:06:08 INFO - 'TMOUT': '86400', 09:06:08 INFO - 'USER': 'cltbld', 09:06:08 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:08 INFO - '_': '/tools/buildbot/bin/python'} 09:06:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 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)) 09:06:09 INFO - Cleaning up... 09:06:09 INFO - Return code: 0 09:06:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:06:09 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')]} 09:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:09 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')]} 09:06:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:09 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 0x23851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2623310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, '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': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', '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 09:06:09 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 09:06:09 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 09:06:09 INFO - Using env: {'DISPLAY': ':0', 09:06:09 INFO - 'HOME': '/home/cltbld', 09:06:09 INFO - 'LANG': 'en_US.UTF-8', 09:06:09 INFO - 'LANGUAGE': 'en_US:en', 09:06:09 INFO - 'LOGNAME': 'cltbld', 09:06:09 INFO - 'MAIL': '/var/mail/cltbld', 09:06:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:09 INFO - 'MOZ_NO_REMOTE': '1', 09:06:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:09 INFO - 'NO_EM_RESTART': '1', 09:06:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:09 INFO - 'PWD': '/builds/slave/test', 09:06:09 INFO - 'SHELL': '/bin/bash', 09:06:09 INFO - 'SHLVL': '1', 09:06:09 INFO - 'TERM': 'linux', 09:06:09 INFO - 'TMOUT': '86400', 09:06:09 INFO - 'USER': 'cltbld', 09:06:09 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:06:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:09 INFO - '_': '/tools/buildbot/bin/python'} 09:06:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:06:09 INFO - Cleaning up... 09:06:09 INFO - Return code: 0 09:06:09 INFO - Running post-action listener: _resource_record_post_action 09:06:09 INFO - Running post-action listener: _start_resource_monitoring 09:06:09 INFO - Starting resource monitoring. 09:06:09 INFO - [mozharness: 2017-04-05 16:06:09.304337Z] Finished create-virtualenv step (success) 09:06:09 INFO - [mozharness: 2017-04-05 16:06:09.304854Z] Running install step. 09:06:09 INFO - Running pre-action listener: _resource_record_pre_action 09:06:09 INFO - Running main action method: install 09:06:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:09 INFO - Reading from file tmpfile_stdout 09:06:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:06:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:06:09 INFO - Reading from file tmpfile_stdout 09:06:09 INFO - Output received: 09:06:09 INFO - Usage: mozinstall [options] installer 09:06:09 INFO - Options: 09:06:09 INFO - -h, --help show this help message and exit 09:06:09 INFO - -d DEST, --destination=DEST 09:06:09 INFO - Directory to install application into. [default: 09:06:09 INFO - "/builds/slave/test"] 09:06:09 INFO - --app=APP Application being installed. [default: firefox] 09:06:09 INFO - mkdir: /builds/slave/test/build/application 09:06:09 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'] 09:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:06:26 INFO - Reading from file tmpfile_stdout 09:06:26 INFO - Output received: 09:06:26 INFO - /builds/slave/test/build/application/firefox/firefox 09:06:26 INFO - Running post-action listener: _resource_record_post_action 09:06:26 INFO - [mozharness: 2017-04-05 16:06:26.160040Z] Finished install step (success) 09:06:26 INFO - [mozharness: 2017-04-05 16:06:26.160317Z] Running run-tests step. 09:06:26 INFO - Running pre-action listener: _resource_record_pre_action 09:06:26 INFO - Running main action method: run_tests 09:06:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:06:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:06:26 INFO - Python 2.7.3 09:06:26 INFO - Return code: 0 09:06:26 INFO - grabbing minidump binary from tooltool 09:06:26 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')]} 09:06:26 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 0x2620400>, '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 0x26213b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2621840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:06:26 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 09:06:26 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 09:06:26 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 09:06:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:06:26 INFO - Return code: 0 09:06:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:06:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:06:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:06:26 INFO - ENV: RUST_BACKTRACE is now 1 09:06:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:06:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:06:26 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/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 09:06:26 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/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --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 09:06:26 INFO - Using env: {'DISPLAY': ':0', 09:06:26 INFO - 'HOME': '/home/cltbld', 09:06:26 INFO - 'LANG': 'en_US.UTF-8', 09:06:26 INFO - 'LANGUAGE': 'en_US:en', 09:06:26 INFO - 'LOGNAME': 'cltbld', 09:06:26 INFO - 'MAIL': '/var/mail/cltbld', 09:06:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:06:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:26 INFO - 'MOZ_NO_REMOTE': '1', 09:06:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:26 INFO - 'NO_EM_RESTART': '1', 09:06:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:26 INFO - 'PWD': '/builds/slave/test', 09:06:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:06:26 INFO - 'RUST_BACKTRACE': '1', 09:06:26 INFO - 'SHELL': '/bin/bash', 09:06:26 INFO - 'SHLVL': '1', 09:06:26 INFO - 'TERM': 'linux', 09:06:26 INFO - 'TMOUT': '86400', 09:06:26 INFO - 'USER': 'cltbld', 09:06:26 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1491408317.36160-376805410', 09:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:26 INFO - '_': '/tools/buildbot/bin/python'} 09:06:26 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/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 09:06:26 INFO - mozversion application_buildid: 20170405151504 09:06:26 INFO - mozversion application_changeset: c549338e76391ddf7725a633bad9dc240ba1ae82 09:06:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:06:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:06:26 INFO - mozversion application_name: Firefox 09:06:26 INFO - mozversion application_remotingname: firefox-dev 09:06:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:06:26 INFO - mozversion application_vendor: Mozilla 09:06:26 INFO - mozversion application_version: 54.0a2 09:06:26 INFO - mozversion platform_buildid: 20170405151504 09:06:26 INFO - mozversion platform_changeset: c549338e76391ddf7725a633bad9dc240ba1ae82 09:06:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:06:26 INFO - mozversion platform_version: 54.0a2 09:06:26 INFO - using testdate: 1491408386 09:06:26 INFO - actual date: 1491408386 09:06:26 INFO - starting webserver on 'localhost:59014' 09:06:26 INFO - SUITE-START | Running 2 tests 09:06:26 INFO - TEST-START | dromaeo_css 09:06:26 INFO - operating with platform_type : linux_ 09:06:26 INFO - Initialising browser for dromaeo_css test... 09:06:26 INFO - TEST-INFO | started process 17610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw6u3JX/profile http://localhost:59014/getInfo.html) 09:06:30 INFO - PROCESS | 17610 | __metrics Screen width/height:1600/1200 09:06:30 INFO - PROCESS | 17610 | colorDepth:24 09:06:30 INFO - PROCESS | 17610 | Browser inner width/height: 1024/697 09:06:30 INFO - PROCESS | 17610 | __metrics 09:06:31 ERROR - PROCESS | 17610 | 1491408391228 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4954:11 < observe@XPIProvider.jsm:2917:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:06:31 INFO - PROCESS | 17610 | 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:06:31 INFO - PROCESS | 17610 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:31 INFO - PROCESS | 17610 | console.error: PushService: 09:06:31 INFO - PROCESS | 17610 | stateChangeProcessEnqueue: Error transitioning state 09:06:31 INFO - PROCESS | 17610 | UnknownError 09:06:32 INFO - TEST-INFO | 17610: exit 0 09:06:32 INFO - Browser initialized. 09:06:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:06:32 INFO - TEST-INFO | started process 17703 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw6u3JX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:44 INFO - PROCESS | 17703 | RSS: Main: 139669504 09:06:44 INFO - PROCESS | 17703 | 09:09:45 INFO - PROCESS | 17703 | Cycle 1(1): loaded http://localhost:59014/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59014/tests/dromaeo/cssquery-ext.html) 09:09:46 INFO - PROCESS | 17703 | RSS: Main: 244555776 09:09:46 INFO - PROCESS | 17703 | 09:11:34 INFO - PROCESS | 17703 | ************************* 09:11:34 INFO - PROCESS | 17703 | A coding exception was thrown and uncaught in a Task. 09:11:34 INFO - PROCESS | 17703 | 09:11:34 ERROR - PROCESS | 17703 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:11:34 INFO - PROCESS | 17703 | Full stack: 09:11:34 INFO - PROCESS | 17703 | ************************* 09:11:34 INFO - PROCESS | 17703 | ************************* 09:11:34 INFO - PROCESS | 17703 | A coding exception was thrown and uncaught in a Task. 09:11:34 INFO - PROCESS | 17703 | 09:11:34 ERROR - PROCESS | 17703 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:11:34 INFO - PROCESS | 17703 | Full stack: 09:11:34 INFO - PROCESS | 17703 | ************************* 09:11:34 INFO - PROCESS | 17703 | ************************* 09:11:34 INFO - PROCESS | 17703 | A coding exception was thrown and uncaught in a Task. 09:11:34 INFO - PROCESS | 17703 | 09:11:34 ERROR - PROCESS | 17703 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:11:34 INFO - PROCESS | 17703 | Full stack: 09:11:34 INFO - PROCESS | 17703 | ************************* 09:11:34 INFO - PROCESS | 17703 | ************************* 09:11:34 INFO - PROCESS | 17703 | A coding exception was thrown and uncaught in a Task. 09:11:34 INFO - PROCESS | 17703 | 09:11:34 ERROR - PROCESS | 17703 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:11:34 INFO - PROCESS | 17703 | Full stack: 09:11:34 INFO - PROCESS | 17703 | ************************* 09:12:49 INFO - PROCESS | 17703 | Cycle 1(1): loaded http://localhost:59014/tests/dromaeo/cssquery-ext.html (next: http://localhost:59014/tests/dromaeo/cssquery-jquery.html) 09:12:49 INFO - PROCESS | 17703 | RSS: Main: 221917184 09:12:49 INFO - PROCESS | 17703 | 09:15:51 INFO - PROCESS | 17703 | Cycle 1(1): loaded http://localhost:59014/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59014/tests/dromaeo/cssquery-mootools.html) 09:15:52 INFO - PROCESS | 17703 | RSS: Main: 213086208 09:15:52 INFO - PROCESS | 17703 | 09:18:54 INFO - PROCESS | 17703 | Cycle 1(1): loaded http://localhost:59014/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59014/tests/dromaeo/cssquery-prototype.html) 09:18:54 INFO - PROCESS | 17703 | RSS: Main: 172474368 09:18:54 INFO - PROCESS | 17703 | 09:21:57 INFO - PROCESS | 17703 | Cycle 1(1): loaded http://localhost:59014/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59014/tests/dromaeo/cssquery-yui.html) 09:21:58 INFO - PROCESS | 17703 | RSS: Main: 177557504 09:21:58 INFO - PROCESS | 17703 | 09:25:00 INFO - PROCESS | 17703 | Cycle 1(1): loaded http://localhost:59014/tests/dromaeo/cssquery-yui.html (next: http://localhost:59014/tests/dromaeo/cssquery-dojo.html) 09:25:01 INFO - PROCESS | 17703 | RSS: Main: 263528448 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - PROCESS | 17703 | __start_tp_report 09:25:01 INFO - PROCESS | 17703 | _x_x_mozilla_page_load 09:25:01 INFO - PROCESS | 17703 | _x_x_mozilla_page_load_details 09:25:01 INFO - PROCESS | 17703 | |i|pagename|runs| 09:25:01 INFO - PROCESS | 17703 | |0;dojo.html;3379.4761811919147;3415.385230658482;3423.589169299686;3450.965490345096;3451.5512983312165;5661.490465858072;5727.656340619561;5748.252727145476;5749.942500574993;5757.798477053304;5756.251687280658;5802.361740208574;5813.33146688131;5813.5349172066235;5821.068629019347;5151.974240128794;5163.7159956202395;5167.715775632339;5191.506806853343;5227.477252274761;5020.246962955568;5024.346834911456;5239.685618862862;5242.685438873661;5255.973720131394;5102.693838369692;5123.513266239701;5148.922766158511;5163.664361816489;5197.168453047513;5215.374155101393;5222.712750798704;5225.372955245357;5230.058589453915;5256.474352564713;4897.436794768576;5081.923771143436;5142.382914050338;5193.974030129825;5204.271402003723;5528;5647.745851436654;5663.4336566343445;5779.306483222041;5790.102534107178;6147.508199344042;6187.659678717669;6190.690465476709;6204.844878878019;6211.440970312694;6202.534809889276;6212.192414986023;6231.252249730016;6245.281792593864;6250.968745156245;5514.558835293167;5538.529225015878;5725.08398656213;5788.160716696056;5802.535797136219;6291.748330066837;6299.874002519924;6307.96846015767;6327.683615819191;6336.556441049082;6051.243594550681;6088.482478989247;6102.176206212193;6108.3280839107665;6108.908366374508;5916.083007133901;5943.524518038633;6188.4739797116845;6195;6195.4424101831055;6033.0347144457555;6053.122297266959;6076.908846367308;6081.361457046946;6108.847278817976;5940.70296485174;5968.313643931004;5969.671668058299;5975.521958243417;6178.258608966953;4625.306204069417;4639.280911458729;4641.767911604406;4645.094206629675;4676.158291507561;4200.91598168041;4214.873553793391;4233.25917964355;4261.552536983634;4271.615554600101;5999.820005399845;6008.3390827009;6008.429199226067;6016.127661489059;6016.458518733335;5992.8801423972145;6027.939720602738;6120.233815636221;6187.412195841388;6215.254169499689;5987.161797348374;5999.460048595647;6007.098935159761;6034.456898879122;6036.909446358308;5223.843284701465;5251.658642188246;5253.763580638881;5254.15933450646;5255.868603284948;6120.142398366865;6174.907376389448;6388.488920886318;6436.549441539048;6520.413162815369;6131.816045518642;6159.384061593984;6173.074038894202;6219;6237.96881015592;6416.935830641633;6424.229092508929;6430.196225471816;6467.611943283418;6492.058651495601;6019.849503762441;6089.056196289582;6107.877842443215;6110.694465276807;6113.57204995646;5925.52595792327;6003.129546215687;6107.0228763397645;6110.808392363447;6220.377962203744;6045.637261764308;6064.060070688874;6064.3935606439;6069.393060693896;6088.817335479943;5940.970295148497;6002.759889604367;6005.099235114768;6062;6067.332593414632;5724.398938111438;5747.540196784331;5864.648121112747;5962.046072628359;5981.730822112929;6049.78825741097;6082.482988945989;6100.969495152496;6119.724612392364;6125.724342404692;5717.599768016199;5750.0512415450985;5941.1088336749835;5946.702664866826;5949.940500594938 09:25:01 INFO - PROCESS | 17703 | |1;ext.html;729.4784229276066;773.79494433975;775.414562005686;787.0358810457187;792.8136887831358;26776.06283780068;42526.574734252645;43142.568574314246;43189.784051079776;43286.567134328645;44061.33907991383;44237.336439953426;44265.33601995972;44287.11425771483;44322;7746;7896.131425543185;9064.818703625924;9203.723888283328;10750.247482676215;10804.945975270113;10873.782524349508;10904.781904361947;10909.945450272737;10938.179636527253;10946.179036572288;10950.616728414507;10981.560737570488;10988.340699558012;10991.725206869813;10941.179411544126;10951.616693415692;10971.615993440191;10973.286736362112;10983.231173817796;10669.253152279343;10734.516946737416;10737.355758654505;10749.83875241872;10767.6231331903;41770.791146044074;41910.371344429856;41933.370999435036;41982.160356792694;41995;4166.708330416872;4213.220554197484;4230.2174097792;4326.0266440051055;4336.436263285753;29651.110466686034;29658.85170574155;29731;30144.397112057635;30996.845015774776;4325.3079507279;4366.279563871957;4382.671299652538;4426.424564806587;4524.61540769035;242314;243664;243831;243977.99999999822;244012.77993610097;51884.74057629688;52235;52420.21369679458;52494.47505524972;52556.47443525516;54910;55276.72361638166;55367;55425.168622470694;55458.72270638621;15346.769798453031;15675;15726.21368931549;15737.763933541006;15747.291371888292;11451.083913286917;11677.35774532392;13028.80456793149;14183.290835458185;14312.928435357964;13134.474621015244;13180.604581862559;13188.27464489443;13244;13264.204147751167;5483.259759932358;5571.804986825442;5657.632253903483;5657.745401456944;5682.516986056149;1027.5992362978445;1030.3199888073984;1031.9535620897077;1034.1364960258118;1038.0968557354997;28262.293442664097;28350.432991340058;28369.858150709115;28694.282642933675;28899.133026009255;1075.1936047964027;1084.5390708948664;1089.3137323486153;1091.24158709696;1097.1168209591356;14246.928765356108;15045.097294162166;15498.690026199412;16280.43018494347;16332.183390830649;312167;314293;314567;315003;316767;48971.510284897406;48976.510234897905;49076.7546162274;49080.7545962275;49107.263391049164;1387.486629946926;1400.803887455757;1409.1122592766492;1409.3728290910447;1434.1323499282992;26612.467750645264;27159.456810863277;27678.584821227294;27778;27882.86058569694;36083.45874811932;50879;51670;51821.22268165905;51863.74068129635;321434;322586;322645;322910;323302;24759.62860557057;25080;27778.86110569434;29890;30258.2435439107;39132.80433597814;39295.607043929194;39454.40818387784;39553;39684.2063158741;30115.108991485256;30302.33337166477;30604.387912242077;30643.080707578807;30726.231844204074;30034.549481757353;30334;30556.541651874795;30882.69117308798;31069.223269418446;34125.48811767875;34424.483632745025;34645.48031779576;35032.29935401329;35413.645863542064;29055.854720727108;29078.854605726836;29233.561496577142;29537.26156846096;29850.253743656584;23219.047052642494;23822.404439889142;24116.517669646157;24137.396565086012;24383 09:25:01 INFO - PROCESS | 17703 | |2;jquery.html;457.16795432313194;458.91051245007196;463.3281741474862;478.8826737449325;478.99669074900373;20117.698234526477;20307.796922030775;21891;22023.779762202354;22229.77770222297;20660.17359305626;21099.2615258466;22097.337079887588;22116.557668846614;22284.220052298173;6591.967040164793;6657.467402607804;6677.866442671131;6755.425788807957;6859.074025006635;6572.769953051644;6613.371729685672;6619.073329733841;6760.66196690166;7011.193712723026;7455.589942553157;7473.215312392202;7486.989256450364;7488.363489103405;7492.400607951351;7335.083114610674;7401.444891633122;7489.775306740807;7563.735269265604;7763.72826951054;7322.267773222688;7532.510386824893;7556.206598307153;7564.583947882864;7600.353969912564;23151.76848231513;23385;24952.875235623887;25724.614130788053;25740.356491087874;3376.932461350784;3604.116991337113;3615.1504396466808;3672.155404257009;3695.0947017980498;15334.156621385819;16120.838791612168;16285.755713664304;16561.50315490537;16751.581210469834;2152.6232909240975;2235.2958817972285;2253.752087270399;2294.5066810635626;2301.953960920789;107805.46097269542;108761;109700;110176;111780.44109779398;18420.907895460437;18553;18637.81362186388;18888.81111188898;19040.80959190418;32085.839570801996;32089.358212835614;32089.358212835614;32322.030339089866;32323.19192020218;10043;10144.695659130239;10295.176385889112;10483.895161048444;10554.683359499228;8566.914330856736;8590.441621294596;8667.869981950276;8850.336224783166;8892;9002.234810041089;9373.1564159226;9438.716838494856;9536.713898583053;9544.570494327772;3589.802560859178;3670.5595328560744;3892.630200130983;3924.7056470764805;3993.4209539616595;952.9523523823781;955.3694561589317;955.6511873166244;972.8248915195333;979.1481411172325;19687.606247874963;20882.37352879416;20902;21241.04415301315;21317.893410533157;969.9233850426021;975.5609975510906;990.1435258501344;997.5560875410517;1000.6497725795913;10745.40900250478;11205.495752691148;11431.542738290542;11468.426578671033;11484.725910117346;631942;641525;641878;645415;653038;109251;113252;114973;116576.41711791386;116971;1256.9308688022247;1293.204679122333;1315.3160356614508;1328.747537967783;1329.6011196641161;18899.527511812314;19140.425787226406;19316.710249346554;19344.70982935229;19469.805301947366;49045.57849630038;50494;51257;52311;54482.72758636181;62257;62301.68849155725;64343.67828160829;72847.63576182086;73943.6302818504;11991.520339186334;12350.3824808761;12400.387949566499;12419.751604967669;12540.122191446511;13326.267055311662;13511.256880871253;13524.526641567498;13633.659158520719;13775.311234438037;12234.694132646755;12278.324692142018;12343.13598048128;12411.689707757017;12466.750664986468;11070.335779853234;11103.444827758418;11240.775184496428;11297.26567773092;11421.771564568497;11261.268017578832;11333.829992549954;11530.82703759461;12216.816747748613;12302.323372214256;12077.51689932393;12165.391730413621;12248.203866748632;12502.87497125017;12557.183783054072;10880.401577913291;11042.227044106836;11147.665570032912;11160.386178760245;11441.256318339281 09:25:01 INFO - PROCESS | 17703 | |3;mootools.html;1435.3612642374148;1594.3144447887412;1597.6964376768412;1623.1397359399518;1628.5928517870532;9567.904320956788;11015.614453494129;11461.484233209516;11731.354775487345;11757.588484403048;11466.025387842043;11540.365279909602;11546.769064618702;11558.47986840592;11574.189806713512;2007.5483016321357;2075.3670130610158;2348.2133485282407;2381.1665916929023;2381.5951288281035;2382.797462215714;2432.659427680126;2470.777630013298;2475.752424757528;2476.3190122716305;2397.352714767012;2401.5917294059964;2412.336607432961;2412.6742889709926;2431.55016321981;2384.749601291874;2393.270052633946;2400.1239547565187;2400.699912510936;2404.5431368040104;2283.4291427143216;2288.084766093559;2289.427643089228;2294.66727324537;2295.8048565871923;11475;11535.942320288344;11576.826347604792;11585.478653460614;11690.532378704842;5670.6030577859565;5824.854378640525;5827.125931110325;5829.213056237396;5829.621074630136;3355.697987181141;3475;3481.651834816523;3569.5359603251673;3644.526211592476;1316.4536717262324;1336.6858788184866;1427.521780203633;1470;1476.6086986948467;17052.744208836877;17130.400435984804;17158.056306903116;17159.914200429044;17177.65644687112;4692.41344831896;4871.074495845778;4896.314515967767;4917.58200552957;4960.975195124001;13205.273709945854;13272.601821945356;13294.268815215062;13307.334633268298;13349.199048057148;5855.414458554195;5858.062709966385;5860.296764388216;5865.325487568899;5865.4134586541;4553.886152846139;4685.648576356787;5282.207668849626;5329.680219186861;5529.364123125812;3954.189391174802;4041.939370909439;4063.187362527506;4092.8976775580254;4118.897027574275;1073.6188653028062;1073.973150671224;1075.4300220882944;1088.6842815583393;1095.4741723972538;1047.586203449633;1060.0936199549428;1064.3932958213898;1067.2742535076222;1071.3946620159586;6671.5997040177745;6799.558028728117;6816.522843400914;6900.447964162855;6903.309669033057;1015.806996670645;1016.7661437869332;1035.342557475993;1036.1348274190984;1068.4283908109123;4522.819087236474;4574.885627859264;4645.372874661945;4665.580097791215;4760.690555113906;14147.29263536819;14609.78085328721;14775.778363324558;14791.92604036973;14800.333984970486;14882.330295136744;14899.180545069908;14904.776428353583;14917.254137293092;14930.70138597222;10583.417912014758;10625.5218515167;10674.306170098918;10706.089982351587;10707.1434285257;6736.93263067363;6756.594604323756;6759.662016899234;6782.8643427132165;6876.381125698711;12826.102172848185;12826.294553799258;12863.421146048264;13300.201987880759;13312.134711243738;13046.673833154247;13139.34303284851;13187.406566704713;13299.26854023038;13344.399502022237;9183.265338772748;9258.861117083385;9266.490343031199;9336.486493242937;9349.158575728217;10592.099671528007;10597.841032384365;10687.732806679895;10708.839367409339;10761.40812255302;9226.423509589336;9315.534223288672;9321.813563728823;9497.097775711296;9727.756806079904;9034.14175653312;9108.089191080839;9118.772030699285;9123.908760912305;9364;8353.081161072156;8354.164583541597;8423.70517031901;8430.325573953945;8442.113578074459;6377.7130029147875;6469.288378278292;6506;6538.509611779231;6553.475721942328;6045.395460453919;6069.089636554552;6098.725557349841;6255.437010668879;6274.874502510015 09:25:01 INFO - PROCESS | 17703 | |4;prototype.html;419.6160513130485;423.9745615263083;434.2379124636263;434.35715141590464;435.4926510615135;5241.633085684004;5284.392294886089;5311.9468805311935;5312.609325803384;5330.3603567571845;5191.144870321317;5232.790688372461;5253.60597955153;5287.41838397776;5300.257963885059;4312.935305970413;4421.828215522889;4438.423005009345;4470.709403888745;4487.910241795178;4438.933415998763;4446.733196008234;4454.930816604008;4461.174682683714;4461.884528867783;4452.755098469583;4459.620932220765;4476.9776151119195;4480.8879778005485;4510.571495707903;4416.889577760549;4421.38100665907;4421.602055814976;4532.7280363178315;4538.682292239545;4254.936175957363;4272.679549033804;4277.379779931924;4280.721753086071;4308.18144552534;5180.456052114511;5184.818531351385;5203.635745497817;5204.869878253036;5205.791768329263;5004.073431728811;5214.191800270964;5258.448757616512;5271.789128434858;5286.656367336111;5483.232347471358;5488.231647569303;5495.670259784426;5524.127453724041;5541.113421852484;4833.32333473314;4849.539293767087;4873.975630121827;4875.707457552523;4907.631927605408;5398.786497535992;5401.297831281948;5427.674339539601;5726.942730572724;5798.855028624276;4614.307853821886;4634.791434385461;4654.25531914892;4660;4881.975590122027;4997.578595785256;5045.697258164522;5057.898842023213;5140.485951404829;5148.922766158511;5094.465081166424;5203.895922081537;5279.366476022902;5323.866903327448;5357.946420535744;5468.507834294933;5472.616916815785;5519.227308176858;5524.530414914777;5577.386487486373;1744.6161844394212;1761.9385319176595;1773.3881810775263;1783.3223375117373;1788.624388878324;1402.978955315671;1437.2885421716107;1453.62932452224;1476.29138013754;1486.576325747157;5395.190721391744;5415.53967912724;5424.783008679608;5460.426655201226;5466.945330546723;5609;5643.379228284885;5650.915236271459;5668.858278543069;5670.18554062696;5139.614528910347;5404.648697834628;5418.972905135528;5450.5639548836;5466.371367292732;2936;2958.393529326526;2974.583558301796;2981.3142977114953;3005.1885990782366;5585.776568937197;5863.5309175265875;5869.706514674249;5882.764549471173;5883.676397798076;5754.856128596819;5769.278840144982;5779.537636989031;5782.3639399666;5783.987802134609;5867.442592953663;5946.8810623787285;5983.34183239852;6041.697915104227;6096.939030609726;5477.67133971963;5645.689487078086;5649;5714.200011998407;5729.856753581027;5553.527950124285;5596.901162064226;5610.7194640268635;5614.035087719266;5677.432256774455;5653.943460565506;5746.224259724883;5856.297244330707;5869.8239052828485;5937.495312898452;4617.238155704271;4640.303954406866;4655.4180727409075;4663.673542851968;4663.813447462199;5504.779808807602;5622.409646987007;5624.943750562442;5652.660840349592;5746.770129194785;5493.395726470165;5495.642783219078;5497.505224529811;5502.449755024466;5523.116301391758;5479.6164268500825;5479.780808767764;5509;5530.9347629491485;5577.3028371453565;5281.441974617574;5507.862303442285;5517.558595312285;5525.198846167364;5571.164325351229;5463.235147079333;5515.3526307371885;5589.357223919302;5605.486518640026;5628.690422026828;5301.1518157096225;5433.266509021231;5448.536874365724;5466.453354664502;5480.808171713971 09:25:01 INFO - PROCESS | 17703 | |5;yui.html;792.7542461836834;832.3382910586088;896.9282457403407;897.48394673063;932.906709329067;16770.993740375572;17360.045197514162;17524.912375438136;17702.822971770278;17705.203265853037;17419.564510887234;18047.097645117756;18070.367537136204;18086.909565452155;18136.727949080774;879.3185281406929;884.5577211394303;900.8693739407782;903.1149473515958;908.0193391137589;885.7431344909968;892.3441270666087;894.1103601916103;894.6287290774321;895.0646574329841;949.6581230756922;949.6628696812636;949.8432758594823;949.9857502137439;951.4243882451121;938.990610093904;946.1579194516886;946.2713710442955;946.5976959792096;946.9668561600346;688.5249178067118;688.9517733758639;689.5104475822172;689.6724056073375;689.689639662154;17616.20727067285;17644.294228230854;17663;18470.722939155923;18554.814451855444;15635.765463518057;15800.209989500594;16140;16178;16561.6687666246;13136.540221092311;13245.933770331087;13274.402651880591;13437.798433023512;13593.388297526635;4187.225363307767;4228.112096459748;4276.422682937795;4284.657227421799;4317.525072242051;50404;51389;63455.36544634541;65168.674156629386;66231.4131264883;14043.859561404459;14294.499692510712;14337.78493322601;14373.065750726379;14410.495632652806;26337.868310658323;26439.735602644112;26685.733142668712;26730;26744.59883101755;9533.570989305497;9804.950975245078;9817.410955342559;9887.357321774061;9974.850377244347;4885.096257192431;4936.432310284364;4982.9252561211615;5001;5040.269160971712;3807.904802379963;3895.5130608673917;3902.551206611219;3904.902377440587;3908.6482216600643;637.7480895046431;640.3916279534368;642.8842808294459;655.1188651264042;660.725798793505;814.8818421328874;816.6325153680867;821.6261600971544;847.9321654267644;848.0628905059943;9931.652392166385;10490.265681402381;11575.942120289345;11687.649370518728;11788.351640659674;768.5158350239313;784.3215618489965;785.3834739729285;792.2077922077922;805.7018903005925;4968.701877887338;4971.304017437562;5031.295618613397;5037.319961805183;5042.974785126051;84633;86406.56796715976;86412.56793715991;87346.56326718326;89830;76299;76648.61675691586;77494.61252693701;78938.6053069731;79224.60387698024;69947.65026174836;70181.2981870185;70824;72368.63815680888;73218.26781732221;9765.609375624896;9950.701478955643;9984.850227246452;10046.748831279276;10069.345492542961;43114;43268.78365608152;43501;44263.55736442723;45130.323045153695;50459.495405045476;57295;58960;58989;59684.403155967884;13527.797083043959;13620.93189534046;13665.111767735065;13800;13880.305984700926;16889.155542223085;17245.913770431067;17383.652326953645;17606.82393176103;17774.555636109202;15244.542663720105;15446.150461724263;15529;15702.607434814221;16123.355065797237;15458.613534661723;15514.643516439744;15631.609209769847;15842.44551440694;16116.113613751357;14272;14504.492342767953;14798.704025919205;14866.702665946405;15416.691666166838;10733.19501037441;11017.394043327695;11120.277181983145;11310.773784524428;11610;12670.87329126697;12735.299558523999;12785.680357990752;13082;13172 09:25:01 INFO - PROCESS | 17703 | __end_tp_report 09:25:01 INFO - PROCESS | 17703 | __start_cc_report 09:25:01 INFO - PROCESS | 17703 | _x_x_mozilla_cycle_collect,1033 09:25:01 INFO - PROCESS | 17703 | __end_cc_report 09:25:01 INFO - PROCESS | 17703 | __startTimestamp1491409501005__endTimestamp 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - PROCESS | 17703 | ------- Summary: start ------- 09:25:01 INFO - PROCESS | 17703 | Number of tests: 6 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - PROCESS | 17703 | [#0] dojo.html Cycles:165 Average:5708.82 Median:5981.73 stddev:652.21 (10.9%) stddev-sans-first:628.09 09:25:01 INFO - PROCESS | 17703 | Values: 3379.5 3415.4 3423.6 3451.0 3451.6 5661.5 5727.7 5748.3 5749.9 5757.8 5756.3 5802.4 5813.3 5813.5 5821.1 5152.0 5163.7 5167.7 5191.5 5227.5 5020.2 5024.3 5239.7 5242.7 5256.0 5102.7 5123.5 5148.9 5163.7 5197.2 5215.4 5222.7 5225.4 5230.1 5256.5 4897.4 5081.9 5142.4 5194.0 5204.3 5528.0 5647.7 5663.4 5779.3 5790.1 6147.5 6187.7 6190.7 6204.8 6211.4 6202.5 6212.2 6231.3 6245.3 6251.0 5514.6 5538.5 5725.1 5788.2 5802.5 6291.7 6299.9 6308.0 6327.7 6336.6 6051.2 6088.5 6102.2 6108.3 6108.9 5916.1 5943.5 6188.5 6195.0 6195.4 6033.0 6053.1 6076.9 6081.4 6108.8 5940.7 5968.3 5969.7 5975.5 6178.3 4625.3 4639.3 4641.8 4645.1 4676.2 4200.9 4214.9 4233.3 4261.6 4271.6 5999.8 6008.3 6008.4 6016.1 6016.5 5992.9 6027.9 6120.2 6187.4 6215.3 5987.2 5999.5 6007.1 6034.5 6036.9 5223.8 5251.7 5253.8 5254.2 5255.9 6120.1 6174.9 6388.5 6436.5 6520.4 6131.8 6159.4 6173.1 6219.0 6238.0 6416.9 6424.2 6430.2 6467.6 6492.1 6019.8 6089.1 6107.9 6110.7 6113.6 5925.5 6003.1 6107.0 6110.8 6220.4 6045.6 6064.1 6064.4 6069.4 6088.8 5941.0 6002.8 6005.1 6062.0 6067.3 5724.4 5747.5 5864.6 5962.0 5981.7 6049.8 6082.5 6101.0 6119.7 6125.7 5717.6 5750.1 5941.1 5946.7 5949.9 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - PROCESS | 17703 | [#1] ext.html Cycles:180 Average:45613.34 Median:30129.75 stddev:77333.39 (256.7%) stddev-sans-first:77476.90 09:25:01 INFO - PROCESS | 17703 | Values: 729.5 773.8 775.4 787.0 792.8 26776.1 42526.6 43142.6 43189.8 43286.6 44061.3 44237.3 44265.3 44287.1 44322.0 7746.0 7896.1 9064.8 9203.7 10750.2 10804.9 10873.8 10904.8 10909.9 10938.2 10946.2 10950.6 10981.6 10988.3 10991.7 10941.2 10951.6 10971.6 10973.3 10983.2 10669.3 10734.5 10737.4 10749.8 10767.6 41770.8 41910.4 41933.4 41982.2 41995.0 4166.7 4213.2 4230.2 4326.0 4336.4 29651.1 29658.9 29731.0 30144.4 30996.8 4325.3 4366.3 4382.7 4426.4 4524.6 242314.0 243664.0 243831.0 243978.0 244012.8 51884.7 52235.0 52420.2 52494.5 52556.5 54910.0 55276.7 55367.0 55425.2 55458.7 15346.8 15675.0 15726.2 15737.8 15747.3 11451.1 11677.4 13028.8 14183.3 14312.9 13134.5 13180.6 13188.3 13244.0 13264.2 5483.3 5571.8 5657.6 5657.7 5682.5 1027.6 1030.3 1032.0 1034.1 1038.1 28262.3 28350.4 28369.9 28694.3 28899.1 1075.2 1084.5 1089.3 1091.2 1097.1 14246.9 15045.1 15498.7 16280.4 16332.2 312167.0 314293.0 314567.0 315003.0 316767.0 48971.5 48976.5 49076.8 49080.8 49107.3 1387.5 1400.8 1409.1 1409.4 1434.1 26612.5 27159.5 27678.6 27778.0 27882.9 36083.5 50879.0 51670.0 51821.2 51863.7 321434.0 322586.0 322645.0 322910.0 323302.0 24759.6 25080.0 27778.9 29890.0 30258.2 39132.8 39295.6 39454.4 39553.0 39684.2 30115.1 30302.3 30604.4 30643.1 30726.2 30034.5 30334.0 30556.5 30882.7 31069.2 34125.5 34424.5 34645.5 35032.3 35413.6 29055.9 29078.9 29233.6 29537.3 29850.3 23219.0 23822.4 24116.5 24137.4 24383.0 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - PROCESS | 17703 | [#2] jquery.html Cycles:180 Average:37043.75 Median:22257.00 stddev:105934.86 (476.0%) stddev-sans-first:106196.42 09:25:01 INFO - PROCESS | 17703 | Values: 457.2 458.9 463.3 478.9 479.0 20117.7 20307.8 21891.0 22023.8 22229.8 20660.2 21099.3 22097.3 22116.6 22284.2 6592.0 6657.5 6677.9 6755.4 6859.1 6572.8 6613.4 6619.1 6760.7 7011.2 7455.6 7473.2 7487.0 7488.4 7492.4 7335.1 7401.4 7489.8 7563.7 7763.7 7322.3 7532.5 7556.2 7564.6 7600.4 23151.8 23385.0 24952.9 25724.6 25740.4 3376.9 3604.1 3615.2 3672.2 3695.1 15334.2 16120.8 16285.8 16561.5 16751.6 2152.6 2235.3 2253.8 2294.5 2302.0 107805.5 108761.0 109700.0 110176.0 111780.4 18420.9 18553.0 18637.8 18888.8 19040.8 32085.8 32089.4 32089.4 32322.0 32323.2 10043.0 10144.7 10295.2 10483.9 10554.7 8566.9 8590.4 8667.9 8850.3 8892.0 9002.2 9373.2 9438.7 9536.7 9544.6 3589.8 3670.6 3892.6 3924.7 3993.4 953.0 955.4 955.7 972.8 979.1 19687.6 20882.4 20902.0 21241.0 21317.9 969.9 975.6 990.1 997.6 1000.6 10745.4 11205.5 11431.5 11468.4 11484.7 631942.0 641525.0 641878.0 645415.0 653038.0 109251.0 113252.0 114973.0 116576.4 116971.0 1256.9 1293.2 1315.3 1328.7 1329.6 18899.5 19140.4 19316.7 19344.7 19469.8 49045.6 50494.0 51257.0 52311.0 54482.7 62257.0 62301.7 64343.7 72847.6 73943.6 11991.5 12350.4 12400.4 12419.8 12540.1 13326.3 13511.3 13524.5 13633.7 13775.3 12234.7 12278.3 12343.1 12411.7 12466.8 11070.3 11103.4 11240.8 11297.3 11421.8 11261.3 11333.8 11530.8 12216.8 12302.3 12077.5 12165.4 12248.2 12502.9 12557.2 10880.4 11042.2 11147.7 11160.4 11441.3 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - PROCESS | 17703 | [#3] mootools.html Cycles:180 Average:7109.77 Median:2389.01 stddev:4589.78 (192.1%) stddev-sans-first:4582.85 09:25:01 INFO - PROCESS | 17703 | Values: 1435.4 1594.3 1597.7 1623.1 1628.6 9567.9 11015.6 11461.5 11731.4 11757.6 11466.0 11540.4 11546.8 11558.5 11574.2 2007.5 2075.4 2348.2 2381.2 2381.6 2382.8 2432.7 2470.8 2475.8 2476.3 2397.4 2401.6 2412.3 2412.7 2431.6 2384.7 2393.3 2400.1 2400.7 2404.5 2283.4 2288.1 2289.4 2294.7 2295.8 11475.0 11535.9 11576.8 11585.5 11690.5 5670.6 5824.9 5827.1 5829.2 5829.6 3355.7 3475.0 3481.7 3569.5 3644.5 1316.5 1336.7 1427.5 1470.0 1476.6 17052.7 17130.4 17158.1 17159.9 17177.7 4692.4 4871.1 4896.3 4917.6 4961.0 13205.3 13272.6 13294.3 13307.3 13349.2 5855.4 5858.1 5860.3 5865.3 5865.4 4553.9 4685.6 5282.2 5329.7 5529.4 3954.2 4041.9 4063.2 4092.9 4118.9 1073.6 1074.0 1075.4 1088.7 1095.5 1047.6 1060.1 1064.4 1067.3 1071.4 6671.6 6799.6 6816.5 6900.4 6903.3 1015.8 1016.8 1035.3 1036.1 1068.4 4522.8 4574.9 4645.4 4665.6 4760.7 14147.3 14609.8 14775.8 14791.9 14800.3 14882.3 14899.2 14904.8 14917.3 14930.7 10583.4 10625.5 10674.3 10706.1 10707.1 6736.9 6756.6 6759.7 6782.9 6876.4 12826.1 12826.3 12863.4 13300.2 13312.1 13046.7 13139.3 13187.4 13299.3 13344.4 9183.3 9258.9 9266.5 9336.5 9349.2 10592.1 10597.8 10687.7 10708.8 10761.4 9226.4 9315.5 9321.8 9497.1 9727.8 9034.1 9108.1 9118.8 9123.9 9364.0 8353.1 8354.2 8423.7 8430.3 8442.1 6377.7 6469.3 6506.0 6538.5 6553.5 6045.4 6069.1 6098.7 6255.4 6274.9 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - PROCESS | 17703 | [#4] prototype.html Cycles:180 Average:4861.02 Median:5327.11 stddev:1257.86 (23.6%) stddev-sans-first:1216.41 09:25:01 INFO - PROCESS | 17703 | Values: 419.6 424.0 434.2 434.4 435.5 5241.6 5284.4 5311.9 5312.6 5330.4 5191.1 5232.8 5253.6 5287.4 5300.3 4312.9 4421.8 4438.4 4470.7 4487.9 4438.9 4446.7 4454.9 4461.2 4461.9 4452.8 4459.6 4477.0 4480.9 4510.6 4416.9 4421.4 4421.6 4532.7 4538.7 4254.9 4272.7 4277.4 4280.7 4308.2 5180.5 5184.8 5203.6 5204.9 5205.8 5004.1 5214.2 5258.4 5271.8 5286.7 5483.2 5488.2 5495.7 5524.1 5541.1 4833.3 4849.5 4874.0 4875.7 4907.6 5398.8 5401.3 5427.7 5726.9 5798.9 4614.3 4634.8 4654.3 4660.0 4882.0 4997.6 5045.7 5057.9 5140.5 5148.9 5094.5 5203.9 5279.4 5323.9 5357.9 5468.5 5472.6 5519.2 5524.5 5577.4 1744.6 1761.9 1773.4 1783.3 1788.6 1403.0 1437.3 1453.6 1476.3 1486.6 5395.2 5415.5 5424.8 5460.4 5466.9 5609.0 5643.4 5650.9 5668.9 5670.2 5139.6 5404.6 5419.0 5450.6 5466.4 2936.0 2958.4 2974.6 2981.3 3005.2 5585.8 5863.5 5869.7 5882.8 5883.7 5754.9 5769.3 5779.5 5782.4 5784.0 5867.4 5946.9 5983.3 6041.7 6096.9 5477.7 5645.7 5649.0 5714.2 5729.9 5553.5 5596.9 5610.7 5614.0 5677.4 5653.9 5746.2 5856.3 5869.8 5937.5 4617.2 4640.3 4655.4 4663.7 4663.8 5504.8 5622.4 5624.9 5652.7 5746.8 5493.4 5495.6 5497.5 5502.4 5523.1 5479.6 5479.8 5509.0 5530.9 5577.3 5281.4 5507.9 5517.6 5525.2 5571.2 5463.2 5515.4 5589.4 5605.5 5628.7 5301.2 5433.3 5448.5 5466.5 5480.8 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - PROCESS | 17703 | [#5] yui.html Cycles:180 Average:18821.20 Median:25007.71 stddev:22971.46 (91.9%) stddev-sans-first:22996.01 09:25:01 INFO - PROCESS | 17703 | Values: 792.8 832.3 896.9 897.5 932.9 16771.0 17360.0 17524.9 17702.8 17705.2 17419.6 18047.1 18070.4 18086.9 18136.7 879.3 884.6 900.9 903.1 908.0 885.7 892.3 894.1 894.6 895.1 949.7 949.7 949.8 950.0 951.4 939.0 946.2 946.3 946.6 947.0 688.5 689.0 689.5 689.7 689.7 17616.2 17644.3 17663.0 18470.7 18554.8 15635.8 15800.2 16140.0 16178.0 16561.7 13136.5 13245.9 13274.4 13437.8 13593.4 4187.2 4228.1 4276.4 4284.7 4317.5 50404.0 51389.0 63455.4 65168.7 66231.4 14043.9 14294.5 14337.8 14373.1 14410.5 26337.9 26439.7 26685.7 26730.0 26744.6 9533.6 9805.0 9817.4 9887.4 9974.9 4885.1 4936.4 4982.9 5001.0 5040.3 3807.9 3895.5 3902.6 3904.9 3908.6 637.7 640.4 642.9 655.1 660.7 814.9 816.6 821.6 847.9 848.1 9931.7 10490.3 11575.9 11687.6 11788.4 768.5 784.3 785.4 792.2 805.7 4968.7 4971.3 5031.3 5037.3 5043.0 84633.0 86406.6 86412.6 87346.6 89830.0 76299.0 76648.6 77494.6 78938.6 79224.6 69947.7 70181.3 70824.0 72368.6 73218.3 9765.6 9950.7 9984.9 10046.7 10069.3 43114.0 43268.8 43501.0 44263.6 45130.3 50459.5 57295.0 58960.0 58989.0 59684.4 13527.8 13620.9 13665.1 13800.0 13880.3 16889.2 17245.9 17383.7 17606.8 17774.6 15244.5 15446.2 15529.0 15702.6 16123.4 15458.6 15514.6 15631.6 15842.4 16116.1 14272.0 14504.5 14798.7 14866.7 15416.7 10733.2 11017.4 11120.3 11310.8 11610.0 12670.9 12735.3 12785.7 13082.0 13172.0 09:25:01 INFO - PROCESS | 17703 | -------- Summary: end -------- 09:25:01 INFO - PROCESS | 17703 | 09:25:01 INFO - TEST-INFO | 17703: exit 0 09:25:01 INFO - TEST-OK | dromaeo_css | took 1115354ms 09:25:01 INFO - TEST-START | kraken 09:25:01 INFO - operating with platform_type : linux_ 09:25:01 INFO - Initialising browser for kraken test... 09:25:01 INFO - TEST-INFO | started process 18031 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD47JBb/profile http://localhost:59014/getInfo.html) 09:25:06 INFO - PROCESS | 18031 | __metrics Screen width/height:1600/1200 09:25:06 INFO - PROCESS | 18031 | colorDepth:24 09:25:06 INFO - PROCESS | 18031 | Browser inner width/height: 1024/697 09:25:06 INFO - PROCESS | 18031 | __metrics 09:25:06 ERROR - PROCESS | 18031 | 1491409506802 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4954:11 < observe@XPIProvider.jsm:2917:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:25:06 INFO - PROCESS | 18031 | 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:25:06 INFO - PROCESS | 18031 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:25:07 INFO - TEST-INFO | 18031: exit 0 09:25:07 INFO - Browser initialized. 09:25:07 INFO - Running cycle 1/1 for kraken test... 09:25:07 INFO - TEST-INFO | started process 18126 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD47JBb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:19 INFO - PROCESS | 18126 | RSS: Main: 139919360 09:25:19 INFO - PROCESS | 18126 | 09:25:22 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59014/tests/kraken/driver.html?testName=audio-beat-detection) 09:25:23 INFO - PROCESS | 18126 | RSS: Main: 183816192 09:25:23 INFO - PROCESS | 18126 | 09:25:25 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59014/tests/kraken/driver.html?testName=audio-dft) 09:25:26 INFO - PROCESS | 18126 | RSS: Main: 173985792 09:25:26 INFO - PROCESS | 18126 | 09:25:28 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59014/tests/kraken/driver.html?testName=audio-fft) 09:25:29 INFO - PROCESS | 18126 | RSS: Main: 265289728 09:25:29 INFO - PROCESS | 18126 | 09:25:31 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59014/tests/kraken/driver.html?testName=audio-oscillator) 09:25:32 INFO - PROCESS | 18126 | RSS: Main: 171819008 09:25:32 INFO - PROCESS | 18126 | 09:25:34 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59014/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:25:34 INFO - PROCESS | 18126 | RSS: Main: 173977600 09:25:34 INFO - PROCESS | 18126 | 09:25:37 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59014/tests/kraken/driver.html?testName=imaging-darkroom) 09:25:38 INFO - PROCESS | 18126 | RSS: Main: 213745664 09:25:38 INFO - PROCESS | 18126 | 09:25:41 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59014/tests/kraken/driver.html?testName=imaging-desaturate) 09:25:42 INFO - PROCESS | 18126 | RSS: Main: 213045248 09:25:42 INFO - PROCESS | 18126 | 09:25:45 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59014/tests/kraken/driver.html?testName=json-parse-financial) 09:25:45 INFO - PROCESS | 18126 | RSS: Main: 210739200 09:25:45 INFO - PROCESS | 18126 | 09:25:47 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59014/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:25:47 INFO - PROCESS | 18126 | RSS: Main: 173031424 09:25:47 INFO - PROCESS | 18126 | 09:25:49 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59014/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:25:50 INFO - PROCESS | 18126 | RSS: Main: 179916800 09:25:50 INFO - PROCESS | 18126 | 09:25:52 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59014/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:25:52 INFO - PROCESS | 18126 | RSS: Main: 189612032 09:25:52 INFO - PROCESS | 18126 | 09:25:55 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59014/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:25:56 INFO - PROCESS | 18126 | RSS: Main: 198447104 09:25:56 INFO - PROCESS | 18126 | 09:25:58 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59014/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:25:59 INFO - PROCESS | 18126 | RSS: Main: 194322432 09:25:59 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | Cycle 1(1): loaded http://localhost:59014/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59014/tests/kraken/driver.html?testName=ai-astar) 09:26:01 INFO - PROCESS | 18126 | RSS: Main: 245907456 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | __start_tp_report 09:26:01 INFO - PROCESS | 18126 | _x_x_mozilla_page_load 09:26:01 INFO - PROCESS | 18126 | _x_x_mozilla_page_load_details 09:26:01 INFO - PROCESS | 18126 | |i|pagename|runs| 09:26:01 INFO - PROCESS | 18126 | |0;ai-astar;104;113;112;112;114;114;114;112;113;112 09:26:01 INFO - PROCESS | 18126 | |1;audio-beat-detection;141;117;118;157;117;119;120;116;142;116 09:26:01 INFO - PROCESS | 18126 | |2;audio-dft;161;161;161;160;160;162;160;160;162;168 09:26:01 INFO - PROCESS | 18126 | |3;audio-fft;77;85;80;115;75;76;76;77;91;76 09:26:01 INFO - PROCESS | 18126 | |4;audio-oscillator;83;81;99;82;82;82;99;82;83;106 09:26:01 INFO - PROCESS | 18126 | |5;imaging-gaussian-blur;113;113;114;114;113;113;114;112;114;113 09:26:01 INFO - PROCESS | 18126 | |6;imaging-darkroom;146;146;146;147;146;146;146;147;146;146 09:26:01 INFO - PROCESS | 18126 | |7;imaging-desaturate;94;94;95;94;94;94;94;93;94;94 09:26:01 INFO - PROCESS | 18126 | |8;json-parse-financial;55;54;56;55;53;56;55;53;54;54 09:26:01 INFO - PROCESS | 18126 | |9;json-stringify-tinderbox;52;54;53;53;53;54;53;54;54;54 09:26:01 INFO - PROCESS | 18126 | |10;stanford-crypto-aes;76;75;77;80;81;79;76;80;80;81 09:26:01 INFO - PROCESS | 18126 | |11;stanford-crypto-ccm;127;136;121;130;121;131;118;126;157;131 09:26:01 INFO - PROCESS | 18126 | |12;stanford-crypto-pbkdf2;164;156;161;156;156;162;163;160;158;155 09:26:01 INFO - PROCESS | 18126 | |13;stanford-crypto-sha256-iterative;63;59;63;59;60;61;61;59;58;58 09:26:01 INFO - PROCESS | 18126 | __end_tp_report 09:26:01 INFO - PROCESS | 18126 | __start_cc_report 09:26:01 INFO - PROCESS | 18126 | _x_x_mozilla_cycle_collect,1687 09:26:01 INFO - PROCESS | 18126 | __end_cc_report 09:26:01 INFO - PROCESS | 18126 | __startTimestamp1491409561292__endTimestamp 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | ------- Summary: start ------- 09:26:01 INFO - PROCESS | 18126 | Number of tests: 14 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.94 (2.6%) stddev-sans-first:0.93 09:26:01 INFO - PROCESS | 18126 | Values: 104.0 113.0 112.0 112.0 114.0 114.0 114.0 112.0 113.0 112.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:119.50 stddev:14.73 (12.3%) stddev-sans-first:14.63 09:26:01 INFO - PROCESS | 18126 | Values: 141.0 117.0 118.0 157.0 117.0 119.0 120.0 116.0 142.0 116.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.00 stddev:2.42 (1.5%) stddev-sans-first:2.55 09:26:01 INFO - PROCESS | 18126 | Values: 161.0 161.0 161.0 160.0 160.0 162.0 160.0 160.0 162.0 168.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#3] audio-fft Cycles:10 Average:82.80 Median:77.00 stddev:12.40 (16.1%) stddev-sans-first:12.97 09:26:01 INFO - PROCESS | 18126 | Values: 77.0 85.0 80.0 115.0 75.0 76.0 76.0 77.0 91.0 76.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:82.50 stddev:9.48 (11.5%) stddev-sans-first:9.89 09:26:01 INFO - PROCESS | 18126 | Values: 83.0 81.0 99.0 82.0 82.0 82.0 99.0 82.0 83.0 106.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:26:01 INFO - PROCESS | 18126 | Values: 113.0 113.0 114.0 114.0 113.0 113.0 114.0 112.0 114.0 113.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 09:26:01 INFO - PROCESS | 18126 | Values: 146.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:26:01 INFO - PROCESS | 18126 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.08 (2.0%) stddev-sans-first:1.13 09:26:01 INFO - PROCESS | 18126 | Values: 55.0 54.0 56.0 55.0 53.0 56.0 55.0 53.0 54.0 54.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#9] json-stringify-tinderbox Cycles:10 Average:53.40 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.53 09:26:01 INFO - PROCESS | 18126 | Values: 52.0 54.0 53.0 53.0 53.0 54.0 53.0 54.0 54.0 54.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#10] stanford-crypto-aes Cycles:10 Average:78.50 Median:80.00 stddev:2.27 (2.8%) stddev-sans-first:2.22 09:26:01 INFO - PROCESS | 18126 | Values: 76.0 75.0 77.0 80.0 81.0 79.0 76.0 80.0 80.0 81.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#11] stanford-crypto-ccm Cycles:10 Average:129.80 Median:130.50 stddev:11.04 (8.5%) stddev-sans-first:11.67 09:26:01 INFO - PROCESS | 18126 | Values: 127.0 136.0 121.0 130.0 121.0 131.0 118.0 126.0 157.0 131.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.10 Median:160.50 stddev:3.31 (2.1%) stddev-sans-first:3.00 09:26:01 INFO - PROCESS | 18126 | Values: 164.0 156.0 161.0 156.0 156.0 162.0 163.0 160.0 158.0 155.0 09:26:01 INFO - PROCESS | 18126 | 09:26:01 INFO - PROCESS | 18126 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.10 Median:60.50 stddev:1.85 (3.1%) stddev-sans-first:1.64 09:26:01 INFO - PROCESS | 18126 | Values: 63.0 59.0 63.0 59.0 60.0 61.0 61.0 59.0 58.0 58.0 09:26:01 INFO - PROCESS | 18126 | -------- Summary: end -------- 09:26:01 INFO - PROCESS | 18126 | 09:26:02 INFO - TEST-INFO | 18126: exit 0 09:26:02 INFO - TEST-OK | kraken | took 60550ms 09:26:02 INFO - SUITE-END | took 1175s 09:26:02 INFO - Completed test suite (00:19:36) 09:26:02 INFO - javascript benchmark 09:26:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7851.628879512292, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3379.4761811919147, 3415.385230658482, 3423.589169299686, 3450.965490345096, 3451.5512983312165, 5661.490465858072, 5727.656340619561, 5748.252727145476, 5749.942500574993, 5757.798477053304, 5756.251687280658, 5802.361740208574, 5813.33146688131, 5813.5349172066235, 5821.068629019347, 5151.974240128794, 5163.7159956202395, 5167.715775632339, 5191.506806853343, 5227.477252274761, 5020.246962955568, 5024.346834911456, 5239.685618862862, 5242.685438873661, 5255.973720131394, 5102.693838369692, 5123.513266239701, 5148.922766158511, 5163.664361816489, 5197.168453047513, 5215.374155101393, 5222.712750798704, 5225.372955245357, 5230.058589453915, 5256.474352564713, 4897.436794768576, 5081.923771143436, 5142.382914050338, 5193.974030129825, 5204.271402003723, 5528.0, 5647.745851436654, 5663.4336566343445, 5779.306483222041, 5790.102534107178, 6147.508199344042, 6187.659678717669, 6190.690465476709, 6204.844878878019, 6211.440970312694, 6202.534809889276, 6212.192414986023, 6231.252249730016, 6245.281792593864, 6250.968745156245, 5514.558835293167, 5538.529225015878, 5725.08398656213, 5788.160716696056, 5802.535797136219, 6291.748330066837, 6299.874002519924, 6307.96846015767, 6327.683615819191, 6336.556441049082, 6051.243594550681, 6088.482478989247, 6102.176206212193, 6108.3280839107665, 6108.908366374508, 5916.083007133901, 5943.524518038633, 6188.4739797116845, 6195.0, 6195.4424101831055, 6033.0347144457555, 6053.122297266959, 6076.908846367308, 6081.361457046946, 6108.847278817976, 5940.70296485174, 5968.313643931004, 5969.671668058299, 5975.521958243417, 6178.258608966953, 4625.306204069417, 4639.280911458729, 4641.767911604406, 4645.094206629675, 4676.158291507561, 4200.91598168041, 4214.873553793391, 4233.25917964355, 4261.552536983634, 4271.615554600101, 5999.820005399845, 6008.3390827009, 6008.429199226067, 6016.127661489059, 6016.458518733335, 5992.8801423972145, 6027.939720602738, 6120.233815636221, 6187.412195841388, 6215.254169499689, 5987.161797348374, 5999.460048595647, 6007.098935159761, 6034.456898879122, 6036.909446358308, 5223.843284701465, 5251.658642188246, 5253.763580638881, 5254.15933450646, 5255.868603284948, 6120.142398366865, 6174.907376389448, 6388.488920886318, 6436.549441539048, 6520.413162815369, 6131.816045518642, 6159.384061593984, 6173.074038894202, 6219.0, 6237.96881015592, 6416.935830641633, 6424.229092508929, 6430.196225471816, 6467.611943283418, 6492.058651495601, 6019.849503762441, 6089.056196289582, 6107.877842443215, 6110.694465276807, 6113.57204995646, 5925.52595792327, 6003.129546215687, 6107.0228763397645, 6110.808392363447, 6220.377962203744, 6045.637261764308, 6064.060070688874, 6064.3935606439, 6069.393060693896, 6088.817335479943, 5940.970295148497, 6002.759889604367, 6005.099235114768, 6062.0, 6067.332593414632, 5724.398938111438, 5747.540196784331, 5864.648121112747, 5962.046072628359, 5981.730822112929, 6049.78825741097, 6082.482988945989, 6100.969495152496, 6119.724612392364, 6125.724342404692, 5717.599768016199, 5750.0512415450985, 5941.1088336749835, 5946.702664866826, 5949.940500594938], "value": 5665.565857327213, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.4784229276066, 773.79494433975, 775.414562005686, 787.0358810457187, 792.8136887831358, 26776.06283780068, 42526.574734252645, 43142.568574314246, 43189.784051079776, 43286.567134328645, 44061.33907991383, 44237.336439953426, 44265.33601995972, 44287.11425771483, 44322.0, 7746.0, 7896.131425543185, 9064.818703625924, 9203.723888283328, 10750.247482676215, 10804.945975270113, 10873.782524349508, 10904.781904361947, 10909.945450272737, 10938.179636527253, 10946.179036572288, 10950.616728414507, 10981.560737570488, 10988.340699558012, 10991.725206869813, 10941.179411544126, 10951.616693415692, 10971.615993440191, 10973.286736362112, 10983.231173817796, 10669.253152279343, 10734.516946737416, 10737.355758654505, 10749.83875241872, 10767.6231331903, 41770.791146044074, 41910.371344429856, 41933.370999435036, 41982.160356792694, 41995.0, 4166.708330416872, 4213.220554197484, 4230.2174097792, 4326.0266440051055, 4336.436263285753, 29651.110466686034, 29658.85170574155, 29731.0, 30144.397112057635, 30996.845015774776, 4325.3079507279, 4366.279563871957, 4382.671299652538, 4426.424564806587, 4524.61540769035, 242314.0, 243664.0, 243831.0, 243977.99999999822, 244012.77993610097, 51884.74057629688, 52235.0, 52420.21369679458, 52494.47505524972, 52556.47443525516, 54910.0, 55276.72361638166, 55367.0, 55425.168622470694, 55458.72270638621, 15346.769798453031, 15675.0, 15726.21368931549, 15737.763933541006, 15747.291371888292, 11451.083913286917, 11677.35774532392, 13028.80456793149, 14183.290835458185, 14312.928435357964, 13134.474621015244, 13180.604581862559, 13188.27464489443, 13244.0, 13264.204147751167, 5483.259759932358, 5571.804986825442, 5657.632253903483, 5657.745401456944, 5682.516986056149, 1027.5992362978445, 1030.3199888073984, 1031.9535620897077, 1034.1364960258118, 1038.0968557354997, 28262.293442664097, 28350.432991340058, 28369.858150709115, 28694.282642933675, 28899.133026009255, 1075.1936047964027, 1084.5390708948664, 1089.3137323486153, 1091.24158709696, 1097.1168209591356, 14246.928765356108, 15045.097294162166, 15498.690026199412, 16280.43018494347, 16332.183390830649, 312167.0, 314293.0, 314567.0, 315003.0, 316767.0, 48971.510284897406, 48976.510234897905, 49076.7546162274, 49080.7545962275, 49107.263391049164, 1387.486629946926, 1400.803887455757, 1409.1122592766492, 1409.3728290910447, 1434.1323499282992, 26612.467750645264, 27159.456810863277, 27678.584821227294, 27778.0, 27882.86058569694, 36083.45874811932, 50879.0, 51670.0, 51821.22268165905, 51863.74068129635, 321434.0, 322586.0, 322645.0, 322910.0, 323302.0, 24759.62860557057, 25080.0, 27778.86110569434, 29890.0, 30258.2435439107, 39132.80433597814, 39295.607043929194, 39454.40818387784, 39553.0, 39684.2063158741, 30115.108991485256, 30302.33337166477, 30604.387912242077, 30643.080707578807, 30726.231844204074, 30034.549481757353, 30334.0, 30556.541651874795, 30882.69117308798, 31069.223269418446, 34125.48811767875, 34424.483632745025, 34645.48031779576, 35032.29935401329, 35413.645863542064, 29055.854720727108, 29078.854605726836, 29233.561496577142, 29537.26156846096, 29850.253743656584, 23219.047052642494, 23822.404439889142, 24116.517669646157, 24137.396565086012, 24383.0], "value": 18581.22830788124, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.16795432313194, 458.91051245007196, 463.3281741474862, 478.8826737449325, 478.99669074900373, 20117.698234526477, 20307.796922030775, 21891.0, 22023.779762202354, 22229.77770222297, 20660.17359305626, 21099.2615258466, 22097.337079887588, 22116.557668846614, 22284.220052298173, 6591.967040164793, 6657.467402607804, 6677.866442671131, 6755.425788807957, 6859.074025006635, 6572.769953051644, 6613.371729685672, 6619.073329733841, 6760.66196690166, 7011.193712723026, 7455.589942553157, 7473.215312392202, 7486.989256450364, 7488.363489103405, 7492.400607951351, 7335.083114610674, 7401.444891633122, 7489.775306740807, 7563.735269265604, 7763.72826951054, 7322.267773222688, 7532.510386824893, 7556.206598307153, 7564.583947882864, 7600.353969912564, 23151.76848231513, 23385.0, 24952.875235623887, 25724.614130788053, 25740.356491087874, 3376.932461350784, 3604.116991337113, 3615.1504396466808, 3672.155404257009, 3695.0947017980498, 15334.156621385819, 16120.838791612168, 16285.755713664304, 16561.50315490537, 16751.581210469834, 2152.6232909240975, 2235.2958817972285, 2253.752087270399, 2294.5066810635626, 2301.953960920789, 107805.46097269542, 108761.0, 109700.0, 110176.0, 111780.44109779398, 18420.907895460437, 18553.0, 18637.81362186388, 18888.81111188898, 19040.80959190418, 32085.839570801996, 32089.358212835614, 32089.358212835614, 32322.030339089866, 32323.19192020218, 10043.0, 10144.695659130239, 10295.176385889112, 10483.895161048444, 10554.683359499228, 8566.914330856736, 8590.441621294596, 8667.869981950276, 8850.336224783166, 8892.0, 9002.234810041089, 9373.1564159226, 9438.716838494856, 9536.713898583053, 9544.570494327772, 3589.802560859178, 3670.5595328560744, 3892.630200130983, 3924.7056470764805, 3993.4209539616595, 952.9523523823781, 955.3694561589317, 955.6511873166244, 972.8248915195333, 979.1481411172325, 19687.606247874963, 20882.37352879416, 20902.0, 21241.04415301315, 21317.893410533157, 969.9233850426021, 975.5609975510906, 990.1435258501344, 997.5560875410517, 1000.6497725795913, 10745.40900250478, 11205.495752691148, 11431.542738290542, 11468.426578671033, 11484.725910117346, 631942.0, 641525.0, 641878.0, 645415.0, 653038.0, 109251.0, 113252.0, 114973.0, 116576.41711791386, 116971.0, 1256.9308688022247, 1293.204679122333, 1315.3160356614508, 1328.747537967783, 1329.6011196641161, 18899.527511812314, 19140.425787226406, 19316.710249346554, 19344.70982935229, 19469.805301947366, 49045.57849630038, 50494.0, 51257.0, 52311.0, 54482.72758636181, 62257.0, 62301.68849155725, 64343.67828160829, 72847.63576182086, 73943.6302818504, 11991.520339186334, 12350.3824808761, 12400.387949566499, 12419.751604967669, 12540.122191446511, 13326.267055311662, 13511.256880871253, 13524.526641567498, 13633.659158520719, 13775.311234438037, 12234.694132646755, 12278.324692142018, 12343.13598048128, 12411.689707757017, 12466.750664986468, 11070.335779853234, 11103.444827758418, 11240.775184496428, 11297.26567773092, 11421.771564568497, 11261.268017578832, 11333.829992549954, 11530.82703759461, 12216.816747748613, 12302.323372214256, 12077.51689932393, 12165.391730413621, 12248.203866748632, 12502.87497125017, 12557.183783054072, 10880.401577913291, 11042.227044106836, 11147.665570032912, 11160.386178760245, 11441.256318339281], "value": 11491.052993926587, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.3612642374148, 1594.3144447887412, 1597.6964376768412, 1623.1397359399518, 1628.5928517870532, 9567.904320956788, 11015.614453494129, 11461.484233209516, 11731.354775487345, 11757.588484403048, 11466.025387842043, 11540.365279909602, 11546.769064618702, 11558.47986840592, 11574.189806713512, 2007.5483016321357, 2075.3670130610158, 2348.2133485282407, 2381.1665916929023, 2381.5951288281035, 2382.797462215714, 2432.659427680126, 2470.777630013298, 2475.752424757528, 2476.3190122716305, 2397.352714767012, 2401.5917294059964, 2412.336607432961, 2412.6742889709926, 2431.55016321981, 2384.749601291874, 2393.270052633946, 2400.1239547565187, 2400.699912510936, 2404.5431368040104, 2283.4291427143216, 2288.084766093559, 2289.427643089228, 2294.66727324537, 2295.8048565871923, 11475.0, 11535.942320288344, 11576.826347604792, 11585.478653460614, 11690.532378704842, 5670.6030577859565, 5824.854378640525, 5827.125931110325, 5829.213056237396, 5829.621074630136, 3355.697987181141, 3475.0, 3481.651834816523, 3569.5359603251673, 3644.526211592476, 1316.4536717262324, 1336.6858788184866, 1427.521780203633, 1470.0, 1476.6086986948467, 17052.744208836877, 17130.400435984804, 17158.056306903116, 17159.914200429044, 17177.65644687112, 4692.41344831896, 4871.074495845778, 4896.314515967767, 4917.58200552957, 4960.975195124001, 13205.273709945854, 13272.601821945356, 13294.268815215062, 13307.334633268298, 13349.199048057148, 5855.414458554195, 5858.062709966385, 5860.296764388216, 5865.325487568899, 5865.4134586541, 4553.886152846139, 4685.648576356787, 5282.207668849626, 5329.680219186861, 5529.364123125812, 3954.189391174802, 4041.939370909439, 4063.187362527506, 4092.8976775580254, 4118.897027574275, 1073.6188653028062, 1073.973150671224, 1075.4300220882944, 1088.6842815583393, 1095.4741723972538, 1047.586203449633, 1060.0936199549428, 1064.3932958213898, 1067.2742535076222, 1071.3946620159586, 6671.5997040177745, 6799.558028728117, 6816.522843400914, 6900.447964162855, 6903.309669033057, 1015.806996670645, 1016.7661437869332, 1035.342557475993, 1036.1348274190984, 1068.4283908109123, 4522.819087236474, 4574.885627859264, 4645.372874661945, 4665.580097791215, 4760.690555113906, 14147.29263536819, 14609.78085328721, 14775.778363324558, 14791.92604036973, 14800.333984970486, 14882.330295136744, 14899.180545069908, 14904.776428353583, 14917.254137293092, 14930.70138597222, 10583.417912014758, 10625.5218515167, 10674.306170098918, 10706.089982351587, 10707.1434285257, 6736.93263067363, 6756.594604323756, 6759.662016899234, 6782.8643427132165, 6876.381125698711, 12826.102172848185, 12826.294553799258, 12863.421146048264, 13300.201987880759, 13312.134711243738, 13046.673833154247, 13139.34303284851, 13187.406566704713, 13299.26854023038, 13344.399502022237, 9183.265338772748, 9258.861117083385, 9266.490343031199, 9336.486493242937, 9349.158575728217, 10592.099671528007, 10597.841032384365, 10687.732806679895, 10708.839367409339, 10761.40812255302, 9226.423509589336, 9315.534223288672, 9321.813563728823, 9497.097775711296, 9727.756806079904, 9034.14175653312, 9108.089191080839, 9118.772030699285, 9123.908760912305, 9364.0, 8353.081161072156, 8354.164583541597, 8423.70517031901, 8430.325573953945, 8442.113578074459, 6377.7130029147875, 6469.288378278292, 6506.0, 6538.509611779231, 6553.475721942328, 6045.395460453919, 6069.089636554552, 6098.725557349841, 6255.437010668879, 6274.874502510015], "value": 5368.949829876174, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.6160513130485, 423.9745615263083, 434.2379124636263, 434.35715141590464, 435.4926510615135, 5241.633085684004, 5284.392294886089, 5311.9468805311935, 5312.609325803384, 5330.3603567571845, 5191.144870321317, 5232.790688372461, 5253.60597955153, 5287.41838397776, 5300.257963885059, 4312.935305970413, 4421.828215522889, 4438.423005009345, 4470.709403888745, 4487.910241795178, 4438.933415998763, 4446.733196008234, 4454.930816604008, 4461.174682683714, 4461.884528867783, 4452.755098469583, 4459.620932220765, 4476.9776151119195, 4480.8879778005485, 4510.571495707903, 4416.889577760549, 4421.38100665907, 4421.602055814976, 4532.7280363178315, 4538.682292239545, 4254.936175957363, 4272.679549033804, 4277.379779931924, 4280.721753086071, 4308.18144552534, 5180.456052114511, 5184.818531351385, 5203.635745497817, 5204.869878253036, 5205.791768329263, 5004.073431728811, 5214.191800270964, 5258.448757616512, 5271.789128434858, 5286.656367336111, 5483.232347471358, 5488.231647569303, 5495.670259784426, 5524.127453724041, 5541.113421852484, 4833.32333473314, 4849.539293767087, 4873.975630121827, 4875.707457552523, 4907.631927605408, 5398.786497535992, 5401.297831281948, 5427.674339539601, 5726.942730572724, 5798.855028624276, 4614.307853821886, 4634.791434385461, 4654.25531914892, 4660.0, 4881.975590122027, 4997.578595785256, 5045.697258164522, 5057.898842023213, 5140.485951404829, 5148.922766158511, 5094.465081166424, 5203.895922081537, 5279.366476022902, 5323.866903327448, 5357.946420535744, 5468.507834294933, 5472.616916815785, 5519.227308176858, 5524.530414914777, 5577.386487486373, 1744.6161844394212, 1761.9385319176595, 1773.3881810775263, 1783.3223375117373, 1788.624388878324, 1402.978955315671, 1437.2885421716107, 1453.62932452224, 1476.29138013754, 1486.576325747157, 5395.190721391744, 5415.53967912724, 5424.783008679608, 5460.426655201226, 5466.945330546723, 5609.0, 5643.379228284885, 5650.915236271459, 5668.858278543069, 5670.18554062696, 5139.614528910347, 5404.648697834628, 5418.972905135528, 5450.5639548836, 5466.371367292732, 2936.0, 2958.393529326526, 2974.583558301796, 2981.3142977114953, 3005.1885990782366, 5585.776568937197, 5863.5309175265875, 5869.706514674249, 5882.764549471173, 5883.676397798076, 5754.856128596819, 5769.278840144982, 5779.537636989031, 5782.3639399666, 5783.987802134609, 5867.442592953663, 5946.8810623787285, 5983.34183239852, 6041.697915104227, 6096.939030609726, 5477.67133971963, 5645.689487078086, 5649.0, 5714.200011998407, 5729.856753581027, 5553.527950124285, 5596.901162064226, 5610.7194640268635, 5614.035087719266, 5677.432256774455, 5653.943460565506, 5746.224259724883, 5856.297244330707, 5869.8239052828485, 5937.495312898452, 4617.238155704271, 4640.303954406866, 4655.4180727409075, 4663.673542851968, 4663.813447462199, 5504.779808807602, 5622.409646987007, 5624.943750562442, 5652.660840349592, 5746.770129194785, 5493.395726470165, 5495.642783219078, 5497.505224529811, 5502.449755024466, 5523.116301391758, 5479.6164268500825, 5479.780808767764, 5509.0, 5530.9347629491485, 5577.3028371453565, 5281.441974617574, 5507.862303442285, 5517.558595312285, 5525.198846167364, 5571.164325351229, 5463.235147079333, 5515.3526307371885, 5589.357223919302, 5605.486518640026, 5628.690422026828, 5301.1518157096225, 5433.266509021231, 5448.536874365724, 5466.453354664502, 5480.808171713971], "value": 4505.638388119813, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.7542461836834, 832.3382910586088, 896.9282457403407, 897.48394673063, 932.906709329067, 16770.993740375572, 17360.045197514162, 17524.912375438136, 17702.822971770278, 17705.203265853037, 17419.564510887234, 18047.097645117756, 18070.367537136204, 18086.909565452155, 18136.727949080774, 879.3185281406929, 884.5577211394303, 900.8693739407782, 903.1149473515958, 908.0193391137589, 885.7431344909968, 892.3441270666087, 894.1103601916103, 894.6287290774321, 895.0646574329841, 949.6581230756922, 949.6628696812636, 949.8432758594823, 949.9857502137439, 951.4243882451121, 938.990610093904, 946.1579194516886, 946.2713710442955, 946.5976959792096, 946.9668561600346, 688.5249178067118, 688.9517733758639, 689.5104475822172, 689.6724056073375, 689.689639662154, 17616.20727067285, 17644.294228230854, 17663.0, 18470.722939155923, 18554.814451855444, 15635.765463518057, 15800.209989500594, 16140.0, 16178.0, 16561.6687666246, 13136.540221092311, 13245.933770331087, 13274.402651880591, 13437.798433023512, 13593.388297526635, 4187.225363307767, 4228.112096459748, 4276.422682937795, 4284.657227421799, 4317.525072242051, 50404.0, 51389.0, 63455.36544634541, 65168.674156629386, 66231.4131264883, 14043.859561404459, 14294.499692510712, 14337.78493322601, 14373.065750726379, 14410.495632652806, 26337.868310658323, 26439.735602644112, 26685.733142668712, 26730.0, 26744.59883101755, 9533.570989305497, 9804.950975245078, 9817.410955342559, 9887.357321774061, 9974.850377244347, 4885.096257192431, 4936.432310284364, 4982.9252561211615, 5001.0, 5040.269160971712, 3807.904802379963, 3895.5130608673917, 3902.551206611219, 3904.902377440587, 3908.6482216600643, 637.7480895046431, 640.3916279534368, 642.8842808294459, 655.1188651264042, 660.725798793505, 814.8818421328874, 816.6325153680867, 821.6261600971544, 847.9321654267644, 848.0628905059943, 9931.652392166385, 10490.265681402381, 11575.942120289345, 11687.649370518728, 11788.351640659674, 768.5158350239313, 784.3215618489965, 785.3834739729285, 792.2077922077922, 805.7018903005925, 4968.701877887338, 4971.304017437562, 5031.295618613397, 5037.319961805183, 5042.974785126051, 84633.0, 86406.56796715976, 86412.56793715991, 87346.56326718326, 89830.0, 76299.0, 76648.61675691586, 77494.61252693701, 78938.6053069731, 79224.60387698024, 69947.65026174836, 70181.2981870185, 70824.0, 72368.63815680888, 73218.26781732221, 9765.609375624896, 9950.701478955643, 9984.850227246452, 10046.748831279276, 10069.345492542961, 43114.0, 43268.78365608152, 43501.0, 44263.55736442723, 45130.323045153695, 50459.495405045476, 57295.0, 58960.0, 58989.0, 59684.403155967884, 13527.797083043959, 13620.93189534046, 13665.111767735065, 13800.0, 13880.305984700926, 16889.155542223085, 17245.913770431067, 17383.652326953645, 17606.82393176103, 17774.555636109202, 15244.542663720105, 15446.150461724263, 15529.0, 15702.607434814221, 16123.355065797237, 15458.613534661723, 15514.643516439744, 15631.609209769847, 15842.44551440694, 16116.113613751357, 14272.0, 14504.492342767953, 14798.704025919205, 14866.702665946405, 15416.691666166838, 10733.19501037441, 11017.394043327695, 11120.277181983145, 11310.773784524428, 11610.0, 12670.87329126697, 12735.299558523999, 12785.680357990752, 13082.0, 13172.0], "value": 8005.673982149496, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 112.0, 112.0, 114.0, 114.0, 114.0, 112.0, 113.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 118.0, 157.0, 117.0, 119.0, 120.0, 116.0, 142.0, 116.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 160.0, 162.0, 160.0, 160.0, 162.0, 168.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 85.0, 80.0, 115.0, 75.0, 76.0, 76.0, 77.0, 91.0, 76.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 99.0, 82.0, 82.0, 82.0, 99.0, 82.0, 83.0, 106.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 112.0, 114.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.2, "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, 93.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 56.0, 55.0, 53.0, 56.0, 55.0, 53.0, 54.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 53.0, 53.0, 53.0, 54.0, 53.0, 54.0, 54.0, 54.0], "value": 53.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 80.0, 81.0, 79.0, 76.0, 80.0, 80.0, 81.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 136.0, 121.0, 130.0, 121.0, 131.0, 118.0, 126.0, 157.0, 131.0], "value": 129.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 156.0, 161.0, 156.0, 156.0, 162.0, 163.0, 160.0, 158.0, 155.0], "value": 159.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 59.0, 63.0, 59.0, 60.0, 61.0, 61.0, 59.0, 58.0, 58.0], "value": 60.1, "unit": "score"}]}]} 09:26:02 INFO - Return code: 0 09:26:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:26:02 ERROR - # TBPL SUCCESS # 09:26:02 INFO - Running post-action listener: _resource_record_post_action 09:26:02 INFO - [mozharness: 2017-04-05 16:26:02.603785Z] Finished run-tests step (success) 09:26:02 INFO - Running post-run listener: _resource_record_post_run 09:26:02 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 262144; Write bytes: 365486080; Read time: 12; Write time: 201256 09:26:02 INFO - TinderboxPrint: CPU usage
13.1% 09:26:02 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 12 09:26:02 INFO - TinderboxPrint: I/O write bytes / time
365,486,080 / 201,256 09:26:02 INFO - TinderboxPrint: CPU idle
8,271.2 (86.9%) 09:26:02 INFO - TinderboxPrint: CPU user
1,203.2 (12.6%) 09:26:02 INFO - TinderboxPrint: Swap in / out
0 / 0 09:26:02 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110092288; Read time: 0; Write time: 74496 09:26:02 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 262144; Write bytes: 252559360; Read time: 12; Write time: 126584 09:26:03 INFO - Running post-run listener: _upload_blobber_files 09:26:03 INFO - Blob upload gear active. 09:26:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:26:03 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:26:03 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:26:03 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:26:03 INFO - (blobuploader) - INFO - Open directory for files ... 09:26:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:26:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:26:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:04 INFO - (blobuploader) - INFO - Done attempting. 09:26:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:26:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:26:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:05 INFO - (blobuploader) - INFO - Done attempting. 09:26:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:26:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:09 INFO - (blobuploader) - INFO - Done attempting. 09:26:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:26:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:26:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:10 INFO - (blobuploader) - INFO - Done attempting. 09:26:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:26:10 INFO - Return code: 0 09:26:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:26:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:26:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9c0cb3d7e7bd80d09c476e63ad5f010bfa1ce0e36aa73557cb85362ed7ed969253e81c0824aee21694bf73d3fb5129b1bd1f986f74ae13d55bf8983d45c60932", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1186da70b1c0bde9f3b2b0453803bf0af3d83c89c2efd74dc09aa235d13443d7fd0dc4953b3dfd7b3905663026649ad6666fbd1c450e9200754f7a90ec67153a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9fc1e40dd3cef7b85f637fcac38b4a3f0105e54e2cd3164ba53157efee8da3435c6871dffeaff4000f00c6bfebba49c5f60d5fc231019e288c1bc62b8256c275", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fc95254210b684034cdf50b4ef49703dce0b1fc20ddf510f5982663242bc62fc6d23ece01375559932ac36cb95f287a68fd299a97cc014a3d659b07ccaf83646"} 09:26:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:26:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:26:10 INFO - Contents: 09:26:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9c0cb3d7e7bd80d09c476e63ad5f010bfa1ce0e36aa73557cb85362ed7ed969253e81c0824aee21694bf73d3fb5129b1bd1f986f74ae13d55bf8983d45c60932", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1186da70b1c0bde9f3b2b0453803bf0af3d83c89c2efd74dc09aa235d13443d7fd0dc4953b3dfd7b3905663026649ad6666fbd1c450e9200754f7a90ec67153a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9fc1e40dd3cef7b85f637fcac38b4a3f0105e54e2cd3164ba53157efee8da3435c6871dffeaff4000f00c6bfebba49c5f60d5fc231019e288c1bc62b8256c275", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fc95254210b684034cdf50b4ef49703dce0b1fc20ddf510f5982663242bc62fc6d23ece01375559932ac36cb95f287a68fd299a97cc014a3d659b07ccaf83646"} 09:26:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:26:10 INFO - Copying logs to upload dir... 09:26:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:26:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.288009 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-05 09:26:09.802621) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 09:26:09.806319) ========= 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=483e410453545d07cca6935c000001ab-1491408317.36160-376805410 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9c0cb3d7e7bd80d09c476e63ad5f010bfa1ce0e36aa73557cb85362ed7ed969253e81c0824aee21694bf73d3fb5129b1bd1f986f74ae13d55bf8983d45c60932", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1186da70b1c0bde9f3b2b0453803bf0af3d83c89c2efd74dc09aa235d13443d7fd0dc4953b3dfd7b3905663026649ad6666fbd1c450e9200754f7a90ec67153a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9fc1e40dd3cef7b85f637fcac38b4a3f0105e54e2cd3164ba53157efee8da3435c6871dffeaff4000f00c6bfebba49c5f60d5fc231019e288c1bc62b8256c275", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fc95254210b684034cdf50b4ef49703dce0b1fc20ddf510f5982663242bc62fc6d23ece01375559932ac36cb95f287a68fd299a97cc014a3d659b07ccaf83646"} build_url:https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/OL7NM3ZiTnOF_IJKygVZAQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9c0cb3d7e7bd80d09c476e63ad5f010bfa1ce0e36aa73557cb85362ed7ed969253e81c0824aee21694bf73d3fb5129b1bd1f986f74ae13d55bf8983d45c60932", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1186da70b1c0bde9f3b2b0453803bf0af3d83c89c2efd74dc09aa235d13443d7fd0dc4953b3dfd7b3905663026649ad6666fbd1c450e9200754f7a90ec67153a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9fc1e40dd3cef7b85f637fcac38b4a3f0105e54e2cd3164ba53157efee8da3435c6871dffeaff4000f00c6bfebba49c5f60d5fc231019e288c1bc62b8256c275", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fc95254210b684034cdf50b4ef49703dce0b1fc20ddf510f5982663242bc62fc6d23ece01375559932ac36cb95f287a68fd299a97cc014a3d659b07ccaf83646"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 09:26:09.834419) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 09:26:09.834931) ========= 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=483e410453545d07cca6935c000001ab-1491408317.36160-376805410 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004389 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 09:26:09.919857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 09:26:09.920183) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 09:26:09.920550) ========= ========= Total master_lag: 0.18 =========