builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-087 starttime: 1486638268.82 results: success (0) revision: bc736e4fab076b947c7918dba41af3d015e3ad22 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.820057) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.820543) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.820937) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.834862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.835175) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-09 03:04:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 127M=0s 2017-02-09 03:04:29 (127 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091838 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.940934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.941256) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013841 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.991462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:28.991806) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bc736e4fab076b947c7918dba41af3d015e3ad22 --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 bc736e4fab076b947c7918dba41af3d015e3ad22 --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=ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-09 03:04:29,267 truncating revision to first 12 chars 2017-02-09 03:04:29,267 Setting DEBUG logging. 2017-02-09 03:04:29,267 attempt 1/10 2017-02-09 03:04:29,267 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bc736e4fab07?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 03:04:29,795 unpacking tar archive at: mozilla-aurora-bc736e4fab07/testing/mozharness/ program finished with exit code 0 elapsedTime=0.817437 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:29.822268) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:29.822633) ========= script_repo_revision: bc736e4fab076b947c7918dba41af3d015e3ad22 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:29.823070) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:29.823378) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 03:04:29.832679) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-09 03:04:29.832999) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:04:30 INFO - MultiFileLogger online at 20170209 03:04:30 in /builds/slave/test 03:04:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:04:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:04:30 INFO - {'append_to_log': False, 03:04:30 INFO - 'base_work_dir': '/builds/slave/test', 03:04:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:04:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:04:30 INFO - 'branch': 'Mozilla-Aurora', 03:04:30 INFO - 'buildbot_json_path': 'buildprops.json', 03:04:30 INFO - 'config_files': ('talos/linux_config.py',), 03:04:30 INFO - 'default_actions': ('clobber', 03:04:30 INFO - 'read-buildbot-config', 03:04:30 INFO - 'download-and-extract', 03:04:30 INFO - 'populate-webroot', 03:04:30 INFO - 'create-virtualenv', 03:04:30 INFO - 'install', 03:04:30 INFO - 'run-tests'), 03:04:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:04:30 INFO - 'download_minidump_stackwalk': True, 03:04:30 INFO - 'download_symbols': 'ondemand', 03:04:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:04:30 INFO - 'tooltool.py': '/tools/tooltool.py', 03:04:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:04:30 INFO - '/tools/misc-python/virtualenv.py')}, 03:04:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:04:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:04:30 INFO - 'installer_path': 'installer.exe', 03:04:30 INFO - 'log_level': 'info', 03:04:30 INFO - 'log_name': 'talos', 03:04:30 INFO - 'log_to_console': True, 03:04:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:04:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:04:30 INFO - 'opt_config_files': (), 03:04:30 INFO - 'pip_index': False, 03:04:30 INFO - 'sps_profile': False, 03:04:30 INFO - 'sps_profile_interval': 0, 03:04:30 INFO - 'suite': 'dromaeojs', 03:04:30 INFO - 'system_bits': '32', 03:04:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:04:30 INFO - 'title': 'talos-linux64-ix-087', 03:04:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:04:30 INFO - 'use_talos_json': True, 03:04:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:04:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:04:30 INFO - 'work_dir': 'build'} 03:04:30 INFO - [mozharness: 2017-02-09 11:04:30.208523Z] Running clobber step. 03:04:30 INFO - Running pre-action listener: _resource_record_pre_action 03:04:30 INFO - Running main action method: clobber 03:04:30 INFO - rmtree: /builds/slave/test/build 03:04:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:04:30 INFO - Running post-action listener: _resource_record_post_action 03:04:30 INFO - [mozharness: 2017-02-09 11:04:30.693967Z] Finished clobber step (success) 03:04:30 INFO - [mozharness: 2017-02-09 11:04:30.694068Z] Running read-buildbot-config step. 03:04:30 INFO - Running pre-action listener: _resource_record_pre_action 03:04:30 INFO - Running main action method: read_buildbot_config 03:04:30 INFO - Using buildbot properties: 03:04:30 INFO - { 03:04:30 INFO - "project": "", 03:04:30 INFO - "product": "firefox", 03:04:30 INFO - "who": "cbook@mozilla.com", 03:04:30 INFO - "installer_path": "public/build/target.tar.bz2", 03:04:30 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:04:30 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 03:04:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:04:30 INFO - "stage_platform": "linux64-pgo", 03:04:30 INFO - "basedir": "/builds/slave/test", 03:04:30 INFO - "buildnumber": 769, 03:04:30 INFO - "platform": "ubuntu64_hw", 03:04:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:04:30 INFO - "slavebuilddir": "test", 03:04:30 INFO - "taskId": "cD1Dt1aiT2ah4u4VyQ5h_w", 03:04:30 INFO - "branch": "mozilla-aurora", 03:04:30 INFO - "script_repo_revision": "production", 03:04:30 INFO - "revision": "bc736e4fab076b947c7918dba41af3d015e3ad22", 03:04:30 INFO - "slavename": "talos-linux64-ix-087", 03:04:30 INFO - "repo_path": "releases/mozilla-aurora" 03:04:30 INFO - } 03:04:30 INFO - Finding installer, test and symbols from parent task. 03:04:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cD1Dt1aiT2ah4u4VyQ5h_w'}, attempt #1 03:04:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cD1Dt1aiT2ah4u4VyQ5h_w'}, attempt #1 03:04:31 INFO - Task dependencies: CWYBlEVpSvecawukjSsUTw 03:04:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw'}, attempt #1 03:04:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.tar.bz2 03:04:32 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/test_packages.json 03:04:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.crashreporter-symbols.zip 03:04:32 INFO - Running post-action listener: _resource_record_post_action 03:04:32 INFO - [mozharness: 2017-02-09 11:04:32.082995Z] Finished read-buildbot-config step (success) 03:04:32 INFO - [mozharness: 2017-02-09 11:04:32.083215Z] Running download-and-extract step. 03:04:32 INFO - Running pre-action listener: _resource_record_pre_action 03:04:32 INFO - Running main action method: download_and_extract 03:04:32 INFO - mkdir: /builds/slave/test/build/tests 03:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:32 INFO - trying https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.test_packages.json 03:04:32 INFO - Downloading https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:04:32 INFO - Downloaded 896 bytes. 03:04:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:04:32 INFO - Using the following test package requirements: 03:04:32 INFO - {u'common': [u'target.common.tests.zip'], 03:04:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:04:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:04:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:04:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:04:32 INFO - u'mozbase': [u'target.common.tests.zip'], 03:04:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:04:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:04:32 INFO - u'web-platform': [u'target.common.tests.zip', 03:04:32 INFO - u'target.web-platform.tests.zip'], 03:04:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:04:32 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:04:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.common.tests.zip 03:04:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:04:32 INFO - Fetch https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.common.tests.zip into memory 03:04:33 INFO - Expected file size: 21856507 03:04:33 INFO - Obtained file size: 21856507 03:04:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:04:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.common.tests.zip 03:04:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:04:37 INFO - Fetch https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.common.tests.zip into memory 03:04:38 INFO - Expected file size: 21856507 03:04:38 INFO - Obtained file size: 21856507 03:04:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.talos.tests.zip 03:04:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:04:42 INFO - Fetch https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.talos.tests.zip into memory 03:04:43 INFO - Expected file size: 14403485 03:04:43 INFO - Obtained file size: 14403485 03:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:43 INFO - trying https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.tar.bz2 03:04:43 INFO - Downloading https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:04:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:04:44 INFO - Downloaded 66118518 bytes. 03:04:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.tar.bz2 03:04:44 INFO - mkdir: /builds/slave/test/properties 03:04:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:04:44 INFO - Writing to file /builds/slave/test/properties/build_url 03:04:44 INFO - Contents: 03:04:44 INFO - build_url:https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.tar.bz2 03:04:44 INFO - Running post-action listener: _resource_record_post_action 03:04:44 INFO - Running post-action listener: set_extra_try_arguments 03:04:44 INFO - [mozharness: 2017-02-09 11:04:44.898225Z] Finished download-and-extract step (success) 03:04:44 INFO - [mozharness: 2017-02-09 11:04:44.898321Z] Running populate-webroot step. 03:04:44 INFO - Running pre-action listener: _resource_record_pre_action 03:04:44 INFO - Running main action method: populate_webroot 03:04:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:04:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:04:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:04:44 INFO - '--tppagecycles', 03:04:44 INFO - '7'], 03:04:44 INFO - 'tests': ['tsvgm']}}, 03:04:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:04:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:04:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:04:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:04:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:04:44 INFO - 'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:04:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:04:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:04:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:04:44 INFO - 'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['damp', 'tps']}, 03:04:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:04:44 INFO - 'tests': ['damp', 'tps']}, 03:04:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['dromaeo_dom']}, 03:04:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:04:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['basic_compositor_video']}, 03:04:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:04:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['a11yr', 03:04:44 INFO - 'ts_paint', 03:04:44 INFO - 'tpaint', 03:04:44 INFO - 'sessionrestore', 03:04:44 INFO - 'sessionrestore_no_auto_restore', 03:04:44 INFO - 'tabpaint']}, 03:04:44 INFO - 'other-e10s': {'tests': ['a11yr', 03:04:44 INFO - 'ts_paint', 03:04:44 INFO - 'tpaint', 03:04:44 INFO - 'sessionrestore', 03:04:44 INFO - 'sessionrestore_no_auto_restore', 03:04:44 INFO - 'tabpaint']}, 03:04:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:04:44 INFO - 'ts_paint', 03:04:44 INFO - 'tpaint', 03:04:44 INFO - 'sessionrestore', 03:04:44 INFO - 'sessionrestore_no_auto_restore', 03:04:44 INFO - 'tabpaint']}, 03:04:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:04:44 INFO - 'ts_paint', 03:04:44 INFO - 'tpaint', 03:04:44 INFO - 'sessionrestore', 03:04:44 INFO - 'sessionrestore_no_auto_restore', 03:04:44 INFO - 'tabpaint']}, 03:04:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['a11yr', 03:04:44 INFO - 'ts_paint', 03:04:44 INFO - 'tpaint', 03:04:44 INFO - 'sessionrestore', 03:04:44 INFO - 'sessionrestore_no_auto_restore', 03:04:44 INFO - 'tabpaint']}, 03:04:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['a11yr', 03:04:44 INFO - 'ts_paint', 03:04:44 INFO - 'tpaint', 03:04:44 INFO - 'sessionrestore', 03:04:44 INFO - 'sessionrestore_no_auto_restore', 03:04:44 INFO - 'tabpaint']}, 03:04:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['tsvgx', 03:04:44 INFO - 'tsvgr_opacity', 03:04:44 INFO - 'tart', 03:04:44 INFO - 'tscrollx', 03:04:44 INFO - 'cart', 03:04:44 INFO - 'tsvg_static']}, 03:04:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:04:44 INFO - 'tsvgr_opacity', 03:04:44 INFO - 'tart', 03:04:44 INFO - 'tscrollx', 03:04:44 INFO - 'cart', 03:04:44 INFO - 'tsvg_static']}, 03:04:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:04:44 INFO - 'talos_options': ['--disable-e10s'], 03:04:44 INFO - 'tests': ['tp5o']}, 03:04:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:04:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:04:44 INFO - 'talos_options': ['--disable-e10s', 03:04:44 INFO - '--xperf_path', 03:04:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:04:44 INFO - 'tests': ['tp5n']}, 03:04:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:04:44 INFO - 'talos_options': ['--xperf_path', 03:04:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:04:44 INFO - 'tests': ['tp5n']}}, 03:04:44 INFO - 'talos.zip': {'path': '', 03:04:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:04:44 INFO - Running post-action listener: _resource_record_post_action 03:04:44 INFO - [mozharness: 2017-02-09 11:04:44.911364Z] Finished populate-webroot step (success) 03:04:44 INFO - [mozharness: 2017-02-09 11:04:44.911452Z] Running create-virtualenv step. 03:04:44 INFO - Running pre-action listener: _resource_record_pre_action 03:04:44 INFO - Running main action method: create_virtualenv 03:04:44 INFO - Creating virtualenv /builds/slave/test/build/venv 03:04:44 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 03:04:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:04:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:04:45 INFO - Using real prefix '/usr' 03:04:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:04:45 INFO - Installing distribute.............................................................................................................................................................................................done. 03:04:47 INFO - Installing pip.................done. 03:04:47 INFO - Return code: 0 03:04:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:04:47 INFO - Reading from file tmpfile_stdout 03:04:47 INFO - Output received: 03:04:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:04:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:04:47 INFO - Using env: {'DISPLAY': ':0', 03:04:47 INFO - 'HOME': '/home/cltbld', 03:04:47 INFO - 'LANG': 'en_US.UTF-8', 03:04:47 INFO - 'LANGUAGE': 'en_US:en', 03:04:47 INFO - 'LOGNAME': 'cltbld', 03:04:47 INFO - 'MAIL': '/var/mail/cltbld', 03:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:47 INFO - 'MOZ_NO_REMOTE': '1', 03:04:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:47 INFO - 'NO_EM_RESTART': '1', 03:04:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:47 INFO - 'PWD': '/builds/slave/test', 03:04:47 INFO - 'SHELL': '/bin/bash', 03:04:47 INFO - 'SHLVL': '1', 03:04:47 INFO - 'TERM': 'linux', 03:04:47 INFO - 'TMOUT': '86400', 03:04:47 INFO - 'USER': 'cltbld', 03:04:47 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:47 INFO - '_': '/tools/buildbot/bin/python'} 03:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:47 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 03:04:47 INFO - Cleaning up... 03:04:47 INFO - Return code: 0 03:04:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:04:47 INFO - Using env: {'DISPLAY': ':0', 03:04:47 INFO - 'HOME': '/home/cltbld', 03:04:47 INFO - 'LANG': 'en_US.UTF-8', 03:04:47 INFO - 'LANGUAGE': 'en_US:en', 03:04:47 INFO - 'LOGNAME': 'cltbld', 03:04:47 INFO - 'MAIL': '/var/mail/cltbld', 03:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:47 INFO - 'MOZ_NO_REMOTE': '1', 03:04:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:47 INFO - 'NO_EM_RESTART': '1', 03:04:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:47 INFO - 'PWD': '/builds/slave/test', 03:04:47 INFO - 'SHELL': '/bin/bash', 03:04:47 INFO - 'SHLVL': '1', 03:04:47 INFO - 'TERM': 'linux', 03:04:47 INFO - 'TMOUT': '86400', 03:04:47 INFO - 'USER': 'cltbld', 03:04:47 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:47 INFO - '_': '/tools/buildbot/bin/python'} 03:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:48 INFO - Downloading/unpacking psutil>=3.1.1 03:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:04:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:04:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:04:50 INFO - Installing collected packages: psutil 03:04:50 INFO - Running setup.py install for psutil 03:04:50 INFO - building 'psutil._psutil_linux' extension 03:04:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:04:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:04:50 INFO - building 'psutil._psutil_posix' extension 03:04:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:04:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:04:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:04:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:04:50 INFO - Successfully installed psutil 03:04:50 INFO - Cleaning up... 03:04:50 INFO - Return code: 0 03:04:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:04:50 INFO - Using env: {'DISPLAY': ':0', 03:04:50 INFO - 'HOME': '/home/cltbld', 03:04:50 INFO - 'LANG': 'en_US.UTF-8', 03:04:50 INFO - 'LANGUAGE': 'en_US:en', 03:04:50 INFO - 'LOGNAME': 'cltbld', 03:04:50 INFO - 'MAIL': '/var/mail/cltbld', 03:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:50 INFO - 'MOZ_NO_REMOTE': '1', 03:04:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:50 INFO - 'NO_EM_RESTART': '1', 03:04:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:50 INFO - 'PWD': '/builds/slave/test', 03:04:50 INFO - 'SHELL': '/bin/bash', 03:04:50 INFO - 'SHLVL': '1', 03:04:50 INFO - 'TERM': 'linux', 03:04:50 INFO - 'TMOUT': '86400', 03:04:50 INFO - 'USER': 'cltbld', 03:04:50 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:50 INFO - '_': '/tools/buildbot/bin/python'} 03:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:04:53 INFO - Installing collected packages: mozsystemmonitor 03:04:53 INFO - Running setup.py install for mozsystemmonitor 03:04:53 INFO - Successfully installed mozsystemmonitor 03:04:53 INFO - Cleaning up... 03:04:53 INFO - Return code: 0 03:04:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:04:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:04:53 INFO - Using env: {'DISPLAY': ':0', 03:04:53 INFO - 'HOME': '/home/cltbld', 03:04:53 INFO - 'LANG': 'en_US.UTF-8', 03:04:53 INFO - 'LANGUAGE': 'en_US:en', 03:04:53 INFO - 'LOGNAME': 'cltbld', 03:04:53 INFO - 'MAIL': '/var/mail/cltbld', 03:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:53 INFO - 'MOZ_NO_REMOTE': '1', 03:04:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:53 INFO - 'NO_EM_RESTART': '1', 03:04:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:53 INFO - 'PWD': '/builds/slave/test', 03:04:53 INFO - 'SHELL': '/bin/bash', 03:04:53 INFO - 'SHLVL': '1', 03:04:53 INFO - 'TERM': 'linux', 03:04:53 INFO - 'TMOUT': '86400', 03:04:53 INFO - 'USER': 'cltbld', 03:04:53 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:53 INFO - '_': '/tools/buildbot/bin/python'} 03:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:53 INFO - Downloading/unpacking jsonschema==2.5.1 03:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:04:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04:55 INFO - Downloading functools32-3.2.3-2.tar.gz 03:04:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:04:55 INFO - warning: no files found matching '*.txt' 03:04:55 INFO - no previously-included directories found matching 'build' 03:04:55 INFO - no previously-included directories found matching 'dist' 03:04:55 INFO - no previously-included directories found matching '.git*' 03:04:55 INFO - Installing collected packages: jsonschema, functools32 03:04:55 INFO - Running setup.py install for functools32 03:04:55 INFO - warning: no files found matching '*.txt' 03:04:55 INFO - no previously-included directories found matching 'build' 03:04:55 INFO - no previously-included directories found matching 'dist' 03:04:55 INFO - no previously-included directories found matching '.git*' 03:04:55 INFO - Successfully installed jsonschema functools32 03:04:55 INFO - Cleaning up... 03:04:55 INFO - Return code: 0 03:04:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:04:55 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')]} 03:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:55 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')]} 03:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:55 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 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:04:55 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 03:04:55 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 03:04:55 INFO - Using env: {'DISPLAY': ':0', 03:04:55 INFO - 'HOME': '/home/cltbld', 03:04:55 INFO - 'LANG': 'en_US.UTF-8', 03:04:55 INFO - 'LANGUAGE': 'en_US:en', 03:04:55 INFO - 'LOGNAME': 'cltbld', 03:04:55 INFO - 'MAIL': '/var/mail/cltbld', 03:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:55 INFO - 'MOZ_NO_REMOTE': '1', 03:04:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:55 INFO - 'NO_EM_RESTART': '1', 03:04:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:55 INFO - 'PWD': '/builds/slave/test', 03:04:55 INFO - 'SHELL': '/bin/bash', 03:04:55 INFO - 'SHLVL': '1', 03:04:55 INFO - 'TERM': 'linux', 03:04:55 INFO - 'TMOUT': '86400', 03:04:55 INFO - 'USER': 'cltbld', 03:04:55 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:55 INFO - '_': '/tools/buildbot/bin/python'} 03:04:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:04:56 INFO - Cleaning up... 03:04:56 INFO - Return code: 0 03:04:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:04:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:04:56 INFO - Using env: {'DISPLAY': ':0', 03:04:56 INFO - 'HOME': '/home/cltbld', 03:04:56 INFO - 'LANG': 'en_US.UTF-8', 03:04:56 INFO - 'LANGUAGE': 'en_US:en', 03:04:56 INFO - 'LOGNAME': 'cltbld', 03:04:56 INFO - 'MAIL': '/var/mail/cltbld', 03:04:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:56 INFO - 'MOZ_NO_REMOTE': '1', 03:04:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:56 INFO - 'NO_EM_RESTART': '1', 03:04:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:56 INFO - 'PWD': '/builds/slave/test', 03:04:56 INFO - 'SHELL': '/bin/bash', 03:04:56 INFO - 'SHLVL': '1', 03:04:56 INFO - 'TERM': 'linux', 03:04:56 INFO - 'TMOUT': '86400', 03:04:56 INFO - 'USER': 'cltbld', 03:04:56 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:04:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:56 INFO - '_': '/tools/buildbot/bin/python'} 03:04:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:56 INFO - Downloading/unpacking blobuploader==1.2.4 03:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:58 INFO - Downloading blobuploader-1.2.4.tar.gz 03:04:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:04:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:04:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:59 INFO - Downloading docopt-0.6.1.tar.gz 03:04:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:04:59 INFO - Installing collected packages: blobuploader, requests, docopt 03:04:59 INFO - Running setup.py install for blobuploader 03:04:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:04:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:04:59 INFO - Running setup.py install for requests 03:04:59 INFO - Running setup.py install for docopt 03:04:59 INFO - Successfully installed blobuploader requests docopt 03:04:59 INFO - Cleaning up... 03:04:59 INFO - Return code: 0 03:04:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:59 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 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:04:59 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 03:04:59 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 03:04:59 INFO - Using env: {'DISPLAY': ':0', 03:04:59 INFO - 'HOME': '/home/cltbld', 03:04:59 INFO - 'LANG': 'en_US.UTF-8', 03:04:59 INFO - 'LANGUAGE': 'en_US:en', 03:04:59 INFO - 'LOGNAME': 'cltbld', 03:04:59 INFO - 'MAIL': '/var/mail/cltbld', 03:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:59 INFO - 'MOZ_NO_REMOTE': '1', 03:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:59 INFO - 'NO_EM_RESTART': '1', 03:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:59 INFO - 'PWD': '/builds/slave/test', 03:04:59 INFO - 'SHELL': '/bin/bash', 03:04:59 INFO - 'SHLVL': '1', 03:04:59 INFO - 'TERM': 'linux', 03:04:59 INFO - 'TMOUT': '86400', 03:04:59 INFO - 'USER': 'cltbld', 03:04:59 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:59 INFO - '_': '/tools/buildbot/bin/python'} 03:05:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:00 INFO - Running setup.py (path:/tmp/pip-RKcGD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:00 INFO - Running setup.py (path:/tmp/pip-E1LCf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:00 INFO - Running setup.py (path:/tmp/pip-oXKMYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:00 INFO - Running setup.py (path:/tmp/pip-YmBagV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:00 INFO - Running setup.py (path:/tmp/pip-ylnhzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:00 INFO - Running setup.py (path:/tmp/pip-sRRG_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:00 INFO - Running setup.py (path:/tmp/pip-kfEg_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:00 INFO - Running setup.py (path:/tmp/pip-JNNZGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:00 INFO - Running setup.py (path:/tmp/pip-KnNfpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:00 INFO - Running setup.py (path:/tmp/pip-Yx8fBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:00 INFO - Running setup.py (path:/tmp/pip-JbDUDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:00 INFO - Running setup.py (path:/tmp/pip-EDA_2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:00 INFO - Running setup.py (path:/tmp/pip-jxYH6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:00 INFO - Running setup.py (path:/tmp/pip-HVCWEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:01 INFO - Running setup.py (path:/tmp/pip-wzUFi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:01 INFO - Running setup.py (path:/tmp/pip-7QNTy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:01 INFO - Running setup.py (path:/tmp/pip-bX4Amw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:05:01 INFO - Running setup.py install for manifestparser 03:05:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:05:01 INFO - Running setup.py install for mozcrash 03:05:01 INFO - Running setup.py install for mozdebug 03:05:01 INFO - Running setup.py install for mozdevice 03:05:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:05:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:05:01 INFO - Running setup.py install for mozfile 03:05:01 INFO - Running setup.py install for mozhttpd 03:05:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:05:01 INFO - Running setup.py install for mozinfo 03:05:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:05:01 INFO - Running setup.py install for mozInstall 03:05:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Running setup.py install for mozleak 03:05:02 INFO - Running setup.py install for mozlog 03:05:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Running setup.py install for moznetwork 03:05:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Running setup.py install for mozprocess 03:05:02 INFO - Running setup.py install for mozprofile 03:05:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Running setup.py install for mozrunner 03:05:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:05:02 INFO - Running setup.py install for mozscreenshot 03:05:02 INFO - Running setup.py install for moztest 03:05:02 INFO - Running setup.py install for mozversion 03:05:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:05:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:05:03 INFO - Cleaning up... 03:05:03 INFO - Return code: 0 03:05:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:05:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:05:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:05:03 INFO - Using env: {'DISPLAY': ':0', 03:05:03 INFO - 'HOME': '/home/cltbld', 03:05:03 INFO - 'LANG': 'en_US.UTF-8', 03:05:03 INFO - 'LANGUAGE': 'en_US:en', 03:05:03 INFO - 'LOGNAME': 'cltbld', 03:05:03 INFO - 'MAIL': '/var/mail/cltbld', 03:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:03 INFO - 'MOZ_NO_REMOTE': '1', 03:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:03 INFO - 'NO_EM_RESTART': '1', 03:05:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:03 INFO - 'PWD': '/builds/slave/test', 03:05:03 INFO - 'SHELL': '/bin/bash', 03:05:03 INFO - 'SHLVL': '1', 03:05:03 INFO - 'TERM': 'linux', 03:05:03 INFO - 'TMOUT': '86400', 03:05:03 INFO - 'USER': 'cltbld', 03:05:03 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:03 INFO - '_': '/tools/buildbot/bin/python'} 03:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:03 INFO - Running setup.py (path:/tmp/pip-jT69m5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:03 INFO - Running setup.py (path:/tmp/pip-4s85ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:03 INFO - Running setup.py (path:/tmp/pip-8SzNWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:03 INFO - Running setup.py (path:/tmp/pip-DMbHpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:03 INFO - Running setup.py (path:/tmp/pip-Mu2N3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:03 INFO - Running setup.py (path:/tmp/pip-y2xCFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:03 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)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:03 INFO - Running setup.py (path:/tmp/pip-hzDd4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:03 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)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:03 INFO - Running setup.py (path:/tmp/pip-hN5YeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:03 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)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:03 INFO - Running setup.py (path:/tmp/pip-f_xpl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:03 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)) 03:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:03 INFO - Running setup.py (path:/tmp/pip-uq7Mgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:04 INFO - Running setup.py (path:/tmp/pip-BZeHf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:04 INFO - Running setup.py (path:/tmp/pip-s04gyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 03:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:04 INFO - Running setup.py (path:/tmp/pip-u0rZHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:04 INFO - Running setup.py (path:/tmp/pip-uT6nAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:04 INFO - Running setup.py (path:/tmp/pip-HuE7up-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:04 INFO - Running setup.py (path:/tmp/pip-o2JJZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:04 INFO - Running setup.py (path:/tmp/pip-9Rrt8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:05:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:06 INFO - Downloading blessings-1.6.tar.gz 03:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:05:06 INFO - Installing collected packages: blessings 03:05:06 INFO - Running setup.py install for blessings 03:05:06 INFO - Successfully installed blessings 03:05:06 INFO - Cleaning up... 03:05:06 INFO - Return code: 0 03:05:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:05:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:07 INFO - Reading from file tmpfile_stdout 03:05:07 INFO - Current package versions: 03:05:07 INFO - argparse == 1.2.1 03:05:07 INFO - blessings == 1.6 03:05:07 INFO - blobuploader == 1.2.4 03:05:07 INFO - docopt == 0.6.1 03:05:07 INFO - functools32 == 3.2.3-2 03:05:07 INFO - jsonschema == 2.5.1 03:05:07 INFO - manifestparser == 1.1 03:05:07 INFO - mozInstall == 1.12 03:05:07 INFO - mozcrash == 1.0 03:05:07 INFO - mozdebug == 0.1 03:05:07 INFO - mozdevice == 0.49 03:05:07 INFO - mozfile == 1.2 03:05:07 INFO - mozhttpd == 0.7 03:05:07 INFO - mozinfo == 0.9 03:05:07 INFO - mozleak == 0.1 03:05:07 INFO - mozlog == 3.4 03:05:07 INFO - moznetwork == 0.27 03:05:07 INFO - mozprocess == 0.24 03:05:07 INFO - mozprofile == 0.28 03:05:07 INFO - mozrunner == 6.13 03:05:07 INFO - mozscreenshot == 0.1 03:05:07 INFO - mozsystemmonitor == 0.3 03:05:07 INFO - moztest == 0.8 03:05:07 INFO - mozversion == 1.4 03:05:07 INFO - psutil == 3.1.1 03:05:07 INFO - requests == 1.2.3 03:05:07 INFO - wsgiref == 0.1.2 03:05:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:05:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:05:07 INFO - Using env: {'DISPLAY': ':0', 03:05:07 INFO - 'HOME': '/home/cltbld', 03:05:07 INFO - 'LANG': 'en_US.UTF-8', 03:05:07 INFO - 'LANGUAGE': 'en_US:en', 03:05:07 INFO - 'LOGNAME': 'cltbld', 03:05:07 INFO - 'MAIL': '/var/mail/cltbld', 03:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:07 INFO - 'MOZ_NO_REMOTE': '1', 03:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:07 INFO - 'NO_EM_RESTART': '1', 03:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:07 INFO - 'PWD': '/builds/slave/test', 03:05:07 INFO - 'SHELL': '/bin/bash', 03:05:07 INFO - 'SHLVL': '1', 03:05:07 INFO - 'TERM': 'linux', 03:05:07 INFO - 'TMOUT': '86400', 03:05:07 INFO - 'USER': 'cltbld', 03:05:07 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:07 INFO - '_': '/tools/buildbot/bin/python'} 03:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:05:07 INFO - Cleaning up... 03:05:07 INFO - Return code: 0 03:05:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24761f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2586a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', '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 03:05:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:05:07 INFO - Using env: {'DISPLAY': ':0', 03:05:07 INFO - 'HOME': '/home/cltbld', 03:05:07 INFO - 'LANG': 'en_US.UTF-8', 03:05:07 INFO - 'LANGUAGE': 'en_US:en', 03:05:07 INFO - 'LOGNAME': 'cltbld', 03:05:07 INFO - 'MAIL': '/var/mail/cltbld', 03:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:07 INFO - 'MOZ_NO_REMOTE': '1', 03:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:07 INFO - 'NO_EM_RESTART': '1', 03:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:07 INFO - 'PWD': '/builds/slave/test', 03:05:07 INFO - 'SHELL': '/bin/bash', 03:05:07 INFO - 'SHLVL': '1', 03:05:07 INFO - 'TERM': 'linux', 03:05:07 INFO - 'TMOUT': '86400', 03:05:07 INFO - 'USER': 'cltbld', 03:05:07 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:07 INFO - '_': '/tools/buildbot/bin/python'} 03:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:05:07 INFO - Cleaning up... 03:05:07 INFO - Return code: 0 03:05:07 INFO - Running post-action listener: _resource_record_post_action 03:05:07 INFO - Running post-action listener: _start_resource_monitoring 03:05:07 INFO - Starting resource monitoring. 03:05:07 INFO - [mozharness: 2017-02-09 11:05:07.543763Z] Finished create-virtualenv step (success) 03:05:07 INFO - [mozharness: 2017-02-09 11:05:07.544258Z] Running install step. 03:05:07 INFO - Running pre-action listener: _resource_record_pre_action 03:05:07 INFO - Running main action method: install 03:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:07 INFO - Reading from file tmpfile_stdout 03:05:07 INFO - Detecting whether we're running mozinstall >=1.0... 03:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:05:07 INFO - Reading from file tmpfile_stdout 03:05:07 INFO - Output received: 03:05:07 INFO - Usage: mozinstall [options] installer 03:05:07 INFO - Options: 03:05:07 INFO - -h, --help show this help message and exit 03:05:07 INFO - -d DEST, --destination=DEST 03:05:07 INFO - Directory to install application into. [default: 03:05:07 INFO - "/builds/slave/test"] 03:05:07 INFO - --app=APP Application being installed. [default: firefox] 03:05:07 INFO - mkdir: /builds/slave/test/build/application 03:05:07 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'] 03:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:05:24 INFO - Reading from file tmpfile_stdout 03:05:24 INFO - Output received: 03:05:24 INFO - /builds/slave/test/build/application/firefox/firefox 03:05:24 INFO - Running post-action listener: _resource_record_post_action 03:05:24 INFO - [mozharness: 2017-02-09 11:05:24.294665Z] Finished install step (success) 03:05:24 INFO - [mozharness: 2017-02-09 11:05:24.294820Z] Running run-tests step. 03:05:24 INFO - Running pre-action listener: _resource_record_pre_action 03:05:24 INFO - Running main action method: run_tests 03:05:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:05:24 INFO - Python 2.7.3 03:05:24 INFO - Return code: 0 03:05:24 INFO - grabbing minidump binary from tooltool 03:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2871850>, '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 0x27e66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e6b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:05:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:05:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:05:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:05:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:05:24 INFO - Return code: 0 03:05:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:05:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:05:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:05:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:05:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:05:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--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 03:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-087 --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 03:05:24 INFO - Using env: {'DISPLAY': ':0', 03:05:24 INFO - 'HOME': '/home/cltbld', 03:05:24 INFO - 'LANG': 'en_US.UTF-8', 03:05:24 INFO - 'LANGUAGE': 'en_US:en', 03:05:24 INFO - 'LOGNAME': 'cltbld', 03:05:24 INFO - 'MAIL': '/var/mail/cltbld', 03:05:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:05:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:24 INFO - 'MOZ_NO_REMOTE': '1', 03:05:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:24 INFO - 'NO_EM_RESTART': '1', 03:05:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:24 INFO - 'PWD': '/builds/slave/test', 03:05:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:05:24 INFO - 'SHELL': '/bin/bash', 03:05:24 INFO - 'SHLVL': '1', 03:05:24 INFO - 'TERM': 'linux', 03:05:24 INFO - 'TMOUT': '86400', 03:05:24 INFO - 'USER': 'cltbld', 03:05:24 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640', 03:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:24 INFO - '_': '/tools/buildbot/bin/python'} 03:05:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--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 03:05:24 INFO - mozversion application_buildid: 20170209101153 03:05:24 INFO - mozversion application_changeset: bc736e4fab076b947c7918dba41af3d015e3ad22 03:05:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:05:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:05:24 INFO - mozversion application_name: Firefox 03:05:24 INFO - mozversion application_remotingname: firefox-dev 03:05:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:05:24 INFO - mozversion application_vendor: Mozilla 03:05:24 INFO - mozversion application_version: 53.0a2 03:05:24 INFO - mozversion platform_buildid: 20170209101153 03:05:24 INFO - mozversion platform_changeset: bc736e4fab076b947c7918dba41af3d015e3ad22 03:05:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:05:24 INFO - mozversion platform_version: 53.0a2 03:05:24 INFO - using testdate: 1486638324 03:05:24 INFO - actual date: 1486638324 03:05:24 INFO - starting webserver on 'localhost:50378' 03:05:24 INFO - SUITE-START | Running 2 tests 03:05:24 INFO - TEST-START | dromaeo_css 03:05:24 INFO - operating with platform_type : linux_ 03:05:24 INFO - Initialising browser for dromaeo_css test... 03:05:24 INFO - TEST-INFO | started process 27866 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXQYB04/profile http://localhost:50378/getInfo.html) 03:05:28 INFO - PROCESS | 27866 | __metrics Screen width/height:1600/1200 03:05:28 INFO - PROCESS | 27866 | colorDepth:24 03:05:28 INFO - PROCESS | 27866 | Browser inner width/height: 1024/697 03:05:28 INFO - PROCESS | 27866 | __metrics 03:05:29 INFO - PROCESS | 27866 | 1486638329326 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) 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:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884: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 03:05:29 INFO - PROCESS | 27866 | 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" 03:05:29 INFO - PROCESS | 27866 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:05:29 INFO - PROCESS | 27866 | console.error: PushService: 03:05:29 INFO - PROCESS | 27866 | stateChangeProcessEnqueue: Error transitioning state 03:05:29 INFO - PROCESS | 27866 | UnknownError 03:05:30 INFO - TEST-INFO | 27866: exit 0 03:05:30 INFO - Browser initialized. 03:05:30 INFO - Running cycle 1/1 for dromaeo_css test... 03:05:30 INFO - TEST-INFO | started process 27959 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXQYB04/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:05:42 INFO - PROCESS | 27959 | RSS: Main: 138039296 03:05:42 INFO - PROCESS | 27959 | 03:08:42 INFO - PROCESS | 27959 | Cycle 1(1): loaded http://localhost:50378/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50378/tests/dromaeo/cssquery-ext.html) 03:08:43 INFO - PROCESS | 27959 | RSS: Main: 245940224 03:08:43 INFO - PROCESS | 27959 | 03:10:32 INFO - PROCESS | 27959 | ************************* 03:10:32 INFO - PROCESS | 27959 | A coding exception was thrown and uncaught in a Task. 03:10:32 INFO - PROCESS | 27959 | 03:10:32 ERROR - PROCESS | 27959 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:32 INFO - PROCESS | 27959 | Full stack: 03:10:32 INFO - PROCESS | 27959 | ************************* 03:11:46 INFO - PROCESS | 27959 | Cycle 1(1): loaded http://localhost:50378/tests/dromaeo/cssquery-ext.html (next: http://localhost:50378/tests/dromaeo/cssquery-jquery.html) 03:11:46 INFO - PROCESS | 27959 | RSS: Main: 226308096 03:11:46 INFO - PROCESS | 27959 | 03:14:48 INFO - PROCESS | 27959 | Cycle 1(1): loaded http://localhost:50378/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50378/tests/dromaeo/cssquery-mootools.html) 03:14:49 INFO - PROCESS | 27959 | RSS: Main: 170307584 03:14:49 INFO - PROCESS | 27959 | 03:17:51 INFO - PROCESS | 27959 | Cycle 1(1): loaded http://localhost:50378/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50378/tests/dromaeo/cssquery-prototype.html) 03:17:51 INFO - PROCESS | 27959 | RSS: Main: 227139584 03:17:51 INFO - PROCESS | 27959 | 03:20:54 INFO - PROCESS | 27959 | Cycle 1(1): loaded http://localhost:50378/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50378/tests/dromaeo/cssquery-yui.html) 03:20:54 INFO - PROCESS | 27959 | RSS: Main: 170520576 03:20:55 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | Cycle 1(1): loaded http://localhost:50378/tests/dromaeo/cssquery-yui.html (next: http://localhost:50378/tests/dromaeo/cssquery-dojo.html) 03:23:57 INFO - PROCESS | 27959 | RSS: Main: 259485696 03:23:57 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | __start_tp_report 03:23:57 INFO - PROCESS | 27959 | _x_x_mozilla_page_load 03:23:57 INFO - PROCESS | 27959 | _x_x_mozilla_page_load_details 03:23:57 INFO - PROCESS | 27959 | |i|pagename|runs| 03:23:57 INFO - PROCESS | 27959 | |0;dojo.html;3254.886078987235;3287.309664970356;3295.5386245925565;3322.23588574628;3342.8997130086104;5447.1012282973315;5503.752331145098;5555.694436805979;5572.554195664347;5613.158026296058;5515.669059856412;5623;5631.239782629344;5642.069058605325;5658.971705141468;5020.39755229373;5031.974840125794;5065;5098.796048158069;5106.923396149042;4894.486078961711;4914.582260507861;5075.9746201268945;5125.538701516863;5128.8974220515565;4934.383202099738;4968.950310496885;4979.402471703401;5060.797568097271;5081.46644602317;5072.644914855961;5078.085944529983;5079.492050794928;5083.567896728782;5102.668326558762;4834.6374021948495;4848.345473361086;4980.260856528895;4999.600031997432;5062.164742817431;5402.405735369106;5451.3458384994055;5452.454754524555;5526.778928842841;5583.581231407661;5979.073243647242;5987.341392446828;5988.371221021774;5993.880122397528;5994.610350327258;5856.1508581255475;6015.3383127855905;6018.729157187937;6023.096535519663;6036.577439579231;5505.559555235572;5517.613767036309;5575.498205161555;5590.608657394025;5697.202391665171;6123.357047510036;6128.877422451526;6145.539084568675;6146.170267013986;6156.722947467375;5858.326292476419;5878.588498805043;5916.083007133901;5924.792632257937;5924.940750592439;5846.7076646167525;5867.325257595431;5869.647821130746;5870.5890587659305;5906.143609176645;5945.791897283574;5953.672548009815;5953.940460595425;5962.493188079062;5982.73077711504;5931.881362372728;5939.524838012949;5941.881162376728;5952;5958.880822383615;4034.2133284009337;4052.4326594276827;4093.038136038029;4121.093359460914;4124.546299907008;3809.847606095725;3811.1996480739354;3829.0427393151713;3835.347990841508;3846.3076646203954;5734.16854556087;5785.4793068623185;5787.855303617359;5799.88400231993;5801.9129713054335;5762.4525670061275;5859;5953.315368732651;5975.163477113208;5987.251593550806;5800.50695690871;5802.21670074543;5806.187133801272;5806.68063256525;5810;5128.25640282157;5133.178691409356;5134.691918484903;5138.717370544656;5169.172932330809;6057.030875059969;6087.999999999912;6098.664573448503;6112.17485639433;6255.78104766331;5992.730327135377;5994.400559944145;6011.639301641915;6015.48868346178;6015.48868346178;6195.101710252078;6204.286507051566;6205.751769929152;6209.689515524296;6217.253929528486;5692.971535142464;5717.285339332583;5740.512056475246;5858.5898987070495;5864.882702346014;5798.188253644409;5836.212111365081;5836.708164591669;5923.318818335774;5943.494802941798;5757.05008673581;5759.712014399347;5761.91357129635;5765.625234359753;5767.163761254511;5629.746661400329;5737.540996720169;5769.07694768835;5773.191753154646;5774.307083150049;5715.885682286248;5726.656400615976;5747.3103227612955;5758.021136406905;5809.970950145223;5849;5853.94146058534;5854.590178687451;5858.47273745365;5870.442307980735;5697.57268204878;5709.8287051388525;5717.056685646821;5721.5136713379825;5721.542276617777 03:23:57 INFO - PROCESS | 27959 | |1;ext.html;545.2668886681856;585.9794907178248;620.2061361457336;645.8127143128493;686.9141357330334;39274.214515709704;47195.292070618925;48099.51900480994;48100.03799924004;53653.195202072;51128;51589;51623.74188129063;51693;51704.48295517053;8736.126387361277;9169.633214671421;9389.342746007782;9407.81184376312;9414.670486532972;8697.652093916236;10840.295380800262;11059;11061.391623460706;11455.198136130473;11263.831042534368;11326.150538709588;11328.716782080473;11338.546458141664;11341.943290283538;11069.391183484906;11117.777044525095;11124.833127503094;11139.554417823278;11140.33158010522;11093.72265693356;11151.107911367071;11155.38645374504;11155.553777848876;11161.888381116247;46325.53674463246;46415.76792116018;46665.53334466646;46689.53310466886;46746;4365.847195348179;4397;4432.20220360335;4499.932501012488;4513.7743112844555;35006.29987400263;35071.4739278911;36500.63499365025;36512.452313215326;37015.81492092549;4583.610393116589;4666.346711460398;4666.719996800169;4713.151632706112;4716.811327546894;338437;339438;339936;339973.30013350025;340828;68997.65501172462;69365.3063469369;69365.65317173382;69443.65278173577;69538.30461695419;72917.27082729212;72954;73002.63498682472;73095.63452182704;73190;17833.286668533114;18312;18318.175682094337;18325.908370458063;18336.81663183378;14027;14297.28513574338;14603.707925841423;15081.472148474752;15271.389144434099;19138.80861191398;19847.30534431288;20109.49726256826;20121.295754648516;20134.798652013586;6890.965545172342;6916.308369163144;6927.134108236471;6991.370776630026;7021.087258656342;866.1035827918074;869.5869462005497;870.6865528409768;873.1661263493348;891.9598618062073;33577.153256571124;34288.48567271493;34321.65678343185;34400.65599344025;34965.452401916344;1076.1874784537624;1111.632223364229;1112.2659045030402;1126.6901602059336;1131.620906996149;17853.553661158316;18010.63978720418;18043;18096.276148953893;18152.183151758203;481331;481610;482883;483495;487571;68218.65890670515;68580;68610;68733.65633171803;68771.3122868775;1495.723291191133;1561.9765703514456;1568.5843251538352;1578.7316156253464;1591.3714082937174;32218.35563288674;33801.15497112592;34028.3194336107;34153.65846341604;34335.65664343325;57619.71190144023;59338.70330648319;59443.108353375595;59498.107528387976;59518;385059;389295;391110;391894;391979;38694;39613;39793.80103099582;40226.59773402228;43076.35385469283;45502.54497455116;50250.743731407005;50490;50729.746351268004;50774.492255078454;43120.536401128884;44375.778121109186;44805;44891.77554112339;46006;43343.78328108339;43818.342724858514;44496.33255501105;45612.77193614011;45705.54294457013;44376;46609.300860486444;47329.76335118302;47945.520544794104;50965.49034509608;45260.54739452563;46474.53525464837;46666.300005499266;47344;48565.75717121392;36241.27517449584;36289.092772680895;36377;36851.81574092113;36883.815580921924 03:23:57 INFO - PROCESS | 27959 | |2;jquery.html;432.58687953004886;445.50548890731284;453.0847687670904;458.401785669701;465.9324397962296;20248;21624.783752162493;21932.342029739095;22136.225232116878;22518.77481225187;22523.77476225237;22894.88552557235;23138.537229255406;23371.5325693486;23577.056917723312;5525.917111243334;5686.431356864301;5720.456556627135;6146.784862529812;6365.108884756138;4339.913201735963;6423.325550817167;6469.74121035158;6470.773522926698;6473.708683109271;4504.684672072956;4545.545445455461;4939.60483161348;7025.437964962791;7159.785206443815;7034.331738484836;7039.472039597051;7171.103612048494;7172.139343278814;7173.031640728513;6333.619982801047;6366.395192456709;6376.521760867953;6381.074744162071;6381.1385462962835;15055.247237638183;15136.167510786903;15154.772678409832;15270.16014119223;15550.144742039183;2812.4375124975086;3151.306712523241;3172.698593633601;3186.9043928682177;3196.568463257454;11023.61417350397;11619.709507262301;11653.592124275694;14205.502807401796;15106.169160696158;2054.845886558499;2056.7737548869613;2070.7722150563427;2073.315805784103;2085.0513016577574;92140.53929730375;92895;97456.51271743666;98709;100418.49790751073;11266.098712103012;11434;11554.306741595363;11560.826587601192;11619.941900290614;15608.375664973273;15608.531744047697;15618.219089045502;15629.06225626466;15666.68666626661;8550.615222315011;8700.738977830675;8841.734747957571;8931.017588065308;8973.147550982647;8883.111688831064;8906.662448467066;8936.597853096626;8971.955140224258;9165.358424910191;4619.95380046202;4694.671373003857;4746.406699162605;4778.116048531033;5283.675182919503;2172.771858954787;2240.6863039174527;2249.3589327041714;2264.29806759905;2270.1260014894383;926.2219735422277;942.7266092833139;949.1125797379473;966.9613215471302;971.3880255439028;20657.586848262952;20854.791452085286;20955.68566471504;20967.685484717742;20988.790112098988;940.501534186882;950.7100334397936;952.3952290295574;955.9378640388353;963.9903600963901;11463.828042579367;11518.769624607461;11563.248388854698;11675.591354302729;11695.766084678258;635380;636434;645521;653958;658246;100681.49659251803;103568.48215758873;106391.46804266084;106676.46661766642;107632;1020.4438580973388;1069.7001446889094;1084.7071290751453;1099.2360309584762;1100.1858624617726;17834.732479012564;18055;18079.728804068214;18188.909055455166;18445.446636600922;45061.77469112633;46064.7696761514;46219.537804622865;47336;48015.27977080416;62933;63313;64638;64687.67656161878;65818.67090664516;9419.62321507132;9539.618415263312;9554;9583.664571739955;9745.134890566067;10294.279400441897;12569;14289;14661.04703194289;14841.703165936837;12643.810342845049;12778.552750653682;12783.16909400886;12907.999260114697;12969.805452918403;12386.876131238572;12428.0678949081;12487.875121249035;12730.17253878495;12802.871971280169;12782;12829.550965716155;12848.293343866177;12859.678508037;13051.151675141085;9575.904240957501;9605.37565058269;9730.221582273543;9853.16248118918;9980.900190997998;12255.509779608716;12646.051546133893;12719.80920286215;12943.352832358532;12951.222926624056 03:23:57 INFO - PROCESS | 27959 | |3;mootools.html;1523.6343277613375;1533.846615338467;1542.973922341643;1543.8378970208128;1544.6833399153172;8244;8747.12528747125;8794.164554367333;8802.823943521134;8913.420627659214;7676.654550545225;7852.725154619589;8410.158984101587;8645.135486451352;8677;2355.4700192456644;2397.2089210560475;2397.4605713714363;2401.927942161738;2406.68713067301;2370.9051637934463;2403.6034054381007;2405.6391541268863;2412.469256763518;2413.3122060212845;2389.307100940725;2390.40239940015;2415.7101147862195;2420.4070002849326;2424.2363655448567;2283;2352.8235382346306;2371.9288421347387;2393.293978276404;2400.5198960208027;2340.1458467659354;2341.7072865891764;2345.44881952741;2399.0883464283634;2401.3156250468705;8485.151484851527;8510.106438823896;8605.526696031715;8606.612702428405;8637.870431943526;5213.296205012312;5336.38631557372;5345.224942383372;5353.411124776271;5355.196720491918;3400.132966093655;3402.9659703402895;3447.775894566845;3466.8266586670816;3489.70337521311;1372.5813626843808;1377.3388773388829;1379.6412932637586;1426.4793350427124;1433.5627633571758;11174.497147628375;11184;11188.328700278009;11202.21584489078;11228.49471773764;4492.303692927601;4867.902641947141;4907.116718990616;4915.631327650441;4923.458419573844;9895;9987.151092157103;9991;10008.349457285252;10039.648612298535;5485.862853428616;5494.945050549443;5497.670139791625;5508.807191748349;5511.22842802008;4782.689125206851;4822.9871726937035;4871.415430148405;4911.631627627942;5004;3885.1646895916947;4004.4994375703036;4046.7571945682766;4060.3300455425115;4064.3903414488063;1232.6671798614325;1233.864274929757;1237.560665963588;1245.850497940253;1247.594531777158;973.9318247722591;985.9704208873745;986.7187851462443;992.3477410112226;993.5826952679892;5682.034054210794;5706.686132262683;5708.942910570924;5807.157962095473;5817.621854579439;597.2922087326532;604.3986233697499;618.7988903606346;625.4308579193004;639.8112556795757;3976.721629485966;4006.4991876015497;4021.93967090488;4035.7174997749876;4054.2499637567144;10355.223358248162;10691.83962240567;10716.089132423831;10721.517531711092;10748.140148788078;10476.423796691255;10632.78734425307;10644.733881652866;10666.306690065121;10722.356658600509;8051.194880512019;8189.22202390772;8191.8771218430575;8194.05768336637;8205.507669539847;5685.77256909719;5724.713764311685;5791.391903850035;5805.477507024388;5916.704164791657;9545.522723863918;9551.617935282511;9564.282678799256;9580.377275477073;9662.75843103928;8965.955170224108;9176.265898727952;9194.448333100036;9409.29430292717;9412.105849944244;7168.8924666129005;7299.5620262784405;7302.56184628924;7418.96290518544;7526.623668816646;8228.876566851623;8491.78770530717;8497.660093596434;8544.572771361283;8570.614322355385;7412.073490813648;7469.066366704162;7481.7755467336065;7503;7647.19704431049;7320;7344.816379590339;7404.185539590533;7443.441741869273;7464.328210460867;6617.43751781104;6739.528233023641;6807.183138023271;6979.092717946634;6979.30206979298;5077.593792496517;5127.717975511383;5133.050385678662;5260.736963151904;5270.839040669531;5230.947690523046;5274.76263568148;5366.973165134149;5389.8113566025;5449.182622606482 03:23:57 INFO - PROCESS | 27959 | |4;prototype.html;404.6964776417687;417.5532180566795;418.178279680428;428.5757100470535;428.9528151903292;5334.9466505334985;5399;5457.481539253775;5461.808836690717;5465.945340546603;5234.42421333653;5309.761060752266;5365.739051322936;5408.026555220059;5418.539424148942;4252.489701235848;4269.850555230568;4274.316109422493;4286.378475121106;4316.82732690692;4029.4761680981433;4147.81334839933;4161.167766446708;4246.341817018367;4277.422547956017;4238.788060596973;4254.191703576326;4270.572942705736;4275.337322714984;4275.444192255018;4272.33778764292;4278.914421711564;4279.8074086665865;4289.120730250323;4298.398224248608;3670.7702919521726;3744;3868.12967082407;3876.9612303876884;3905.3556163233034;5179.300794392747;5187.870303242411;5193.350831146106;5198.246254293144;5230.398504172031;4985.700857948535;4988.850334489971;4993.500649935014;5001.44984051754;5019.749012549394;5470.863228419282;5616.185653080322;5623.690697011662;5636.605437619368;5663.915041274384;4334.631556317717;4341.956580434186;4344.652427805768;4354.346847972798;4375.343698445258;5571.022287088106;5774.191613174159;5811.447912448353;5819.388964158787;5823.272090988627;4377.737335759864;4457.866264012085;4462.308342206963;4477.1493416250805;4477.34338294833;4513.932291015637;4559.635229181724;4563.452385713736;4566.26939689648;4574.6340292776495;4976.3127910928415;5019.824306149268;5034.144195486776;5047.192449208109;5047.520485553867;5386.192071189274;5588.552915766729;5607.299087614048;5615.157726341082;5653.349864765604;1428.899747194675;1432.6131944375168;1433.197409450711;1439.3163247457583;1449.289847974485;955.0878910640322;988.9307748457683;991.613270824379;993.1111655068673;994.9006347985517;5262.815801446931;5338.092524270805;5357.464253574612;5369.275147855068;5424.728763561806;5626.381098079208;5707.3721890592005;5739.909417210717;5740.56945729072;5753.712314384264;5486.231927530149;5486.91769623456;5496;5499.945000550023;5514.531264842453;2848.800583959103;2947.263184203949;2976.687447817991;3023.365093330381;3052.084374687585;5745.839788116519;5798.333191682926;5836.7957121501395;5928.525717942554;5941.970290148522;5543.113101903676;5700.37295897451;5720.342160651495;5764.308283006066;5779.739911703983;5768.202421825496;5863.2670916135485;5868.620874094499;5901.3508514063415;5906.763729450774;5599.664020158803;5621.859453513532;5630.395337328841;5632.605717599728;5634.492895639412;5460.481254280837;5556.305057136959;5767;5787.56593255516;5806.883862322815;5984.16221728967;6026.457618814327;6034.487068599218;6043.456088952015;6058.84852878664;4223.640990515718;4293.742375457483;4297.5702429756775;4312.7627980461375;4323.589259020388;5796.898659217299;5804.738786754522;5813.622114562709;5875.823725288248;5907.881842363043;5585.692786896597;5673.404292549223;5681.517071049007;5683.5453163747625;5706.087026075808;5448.182772584144;5639.492445679908;5639.774409023593;5670.950874088307;5677;5654.519365853784;5660.29246344207;5662;5669.914951275651;5684.010596291265;5422.15956526739;5593.692346920959;5683.5453163747625;5691.032524470767;5732.426757324234;5451.754671039734;5486.423925487767;5520.91718624229;5524.06090964545;5563.415841336601 03:23:57 INFO - PROCESS | 27959 | |5;yui.html;845.0704225352114;863.4430792139067;872.4111224923175;914.9954250228748;920.295973580211;16650.750238746412;17249.13754312284;17509.91245043773;17514.737278940793;17530.737038944426;16865.74701379477;17073.060981646006;17184;17654.558636034104;18146;810.7608255564593;845.5391811462767;850.4684572142411;851.3019324154196;851.4167795060404;719.8992141100224;734.8750712378882;819.1849110135394;839.7606682095604;852.8678054901469;899.4648184330313;901.7024381953972;902.4269588811119;904.8190361927608;906;888.0897080492482;888.4802390601506;888.5157589113916;888.5646033443599;888.5779254853958;552.0890530624501;566.1140315406376;632.1339764522634;642.5758999060645;657.9539432239745;17153.506542689494;17215.225314860738;17228.483145505656;17228.91385543077;17277.740833887503;15170.393184272616;15370.769438458432;15402.460913868073;15563.610909727235;15657.373705051785;13021.804672929818;13291.734165316639;13391.732165356736;13500.594982150551;13792.379342929593;3962.8018599069933;3973.264945985002;3984.621460961204;4061.431399604058;4073.7963101845085;62487.68756156236;82675;85059.57470212672;85275;85782;12346.938265308796;12549.309787961567;12570.87429125697;12795.488180472677;12798.68003299925;20908;21029.263975760776;21752.129914803547;23233.651495227587;23250.418739531648;8617.913820861711;8803.779905502413;8898.377113602115;9037.728868133967;9053.637854485878;4797.856064318076;4933.407991041026;4949.876253093701;4982.252662100714;5073.365829271341;3931.6068393160454;4037.818298176589;4052;4082.795860206978;4105.158442519277;657.0308794528033;658.203573675848;666.4135560706547;669.8191488298132;676.9323067693191;787.3307688464873;798.4849771897094;807.8125156020641;817.1052697296451;835.3776436554806;11036.889631103746;11284.887151128383;11308.886911130783;11309.49107290174;11329.71675708114;797.1749239537168;801.1867954026668;805.2350267246139;807.2734538914906;818.7871153500133;5160.174372100446;5257.4742525747115;5262.526372626482;5276.208568714724;5283.181106928432;59086.11370829441;60349.69825150846;80021;84121.15878841134;84843.57578212193;65801.67099164569;65940;66626.66686666633;67151.66424167847;68288.99999999901;48114.75942620265;59683;60804;61202.38797612056;61690.38309616936;10899.237053406185;11451.656450306504;11470.483828227583;11483.942580287045;11620.954114129769;41100.383494248206;41558.79220603878;42261.57738422699;42680.5731942689;42942.57057429511;41166;44324.77837610791;51719.741401292755;51781.223281650055;51947.74026129845;13624.386902589606;14033.859661403256;14050.78923816164;14052;14254.144751314954;15761.290741916411;15801.36794528206;16016.599585010003;16203.513894583182;16993.40523081686;13688.110272832235;13797.793033104712;13866.930665346608;14659.413623454942;14664.560063198121;14307.64230894236;14503.347349369515;15006.249687515363;15209.771853421986;15484.690306194037;13043.217406955613;13346.599602011955;13429.731405371642;13433.26117063571;13619.319034048456;10688.625898093529;10833.566657333618;12110.45502952387;12225.755484890431;12678.366081696062;11685.386524698632;11921.940390297994;12220.083493738182;12323.075769317158;12492.43784029739 03:23:57 INFO - PROCESS | 27959 | __end_tp_report 03:23:57 INFO - PROCESS | 27959 | __start_cc_report 03:23:57 INFO - PROCESS | 27959 | _x_x_mozilla_cycle_collect,1013 03:23:57 INFO - PROCESS | 27959 | __end_cc_report 03:23:57 INFO - PROCESS | 27959 | __startTimestamp1486639437873__endTimestamp 03:23:57 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | ------- Summary: start ------- 03:23:57 INFO - PROCESS | 27959 | Number of tests: 6 03:23:57 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | [#0] dojo.html Cycles:165 Average:5508.51 Median:5761.91 stddev:668.28 (11.6%) stddev-sans-first:646.52 03:23:57 INFO - PROCESS | 27959 | Values: 3254.9 3287.3 3295.5 3322.2 3342.9 5447.1 5503.8 5555.7 5572.6 5613.2 5515.7 5623.0 5631.2 5642.1 5659.0 5020.4 5032.0 5065.0 5098.8 5106.9 4894.5 4914.6 5076.0 5125.5 5128.9 4934.4 4969.0 4979.4 5060.8 5081.5 5072.6 5078.1 5079.5 5083.6 5102.7 4834.6 4848.3 4980.3 4999.6 5062.2 5402.4 5451.3 5452.5 5526.8 5583.6 5979.1 5987.3 5988.4 5993.9 5994.6 5856.2 6015.3 6018.7 6023.1 6036.6 5505.6 5517.6 5575.5 5590.6 5697.2 6123.4 6128.9 6145.5 6146.2 6156.7 5858.3 5878.6 5916.1 5924.8 5924.9 5846.7 5867.3 5869.6 5870.6 5906.1 5945.8 5953.7 5953.9 5962.5 5982.7 5931.9 5939.5 5941.9 5952.0 5958.9 4034.2 4052.4 4093.0 4121.1 4124.5 3809.8 3811.2 3829.0 3835.3 3846.3 5734.2 5785.5 5787.9 5799.9 5801.9 5762.5 5859.0 5953.3 5975.2 5987.3 5800.5 5802.2 5806.2 5806.7 5810.0 5128.3 5133.2 5134.7 5138.7 5169.2 6057.0 6088.0 6098.7 6112.2 6255.8 5992.7 5994.4 6011.6 6015.5 6015.5 6195.1 6204.3 6205.8 6209.7 6217.3 5693.0 5717.3 5740.5 5858.6 5864.9 5798.2 5836.2 5836.7 5923.3 5943.5 5757.1 5759.7 5761.9 5765.6 5767.2 5629.7 5737.5 5769.1 5773.2 5774.3 5715.9 5726.7 5747.3 5758.0 5810.0 5849.0 5853.9 5854.6 5858.5 5870.4 5697.6 5709.8 5717.1 5721.5 5721.5 03:23:57 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | [#1] ext.html Cycles:180 Average:60743.42 Median:24403.99 stddev:107415.68 (440.2%) stddev-sans-first:107621.91 03:23:57 INFO - PROCESS | 27959 | Values: 545.3 586.0 620.2 645.8 686.9 39274.2 47195.3 48099.5 48100.0 53653.2 51128.0 51589.0 51623.7 51693.0 51704.5 8736.1 9169.6 9389.3 9407.8 9414.7 8697.7 10840.3 11059.0 11061.4 11455.2 11263.8 11326.2 11328.7 11338.5 11341.9 11069.4 11117.8 11124.8 11139.6 11140.3 11093.7 11151.1 11155.4 11155.6 11161.9 46325.5 46415.8 46665.5 46689.5 46746.0 4365.8 4397.0 4432.2 4499.9 4513.8 35006.3 35071.5 36500.6 36512.5 37015.8 4583.6 4666.3 4666.7 4713.2 4716.8 338437.0 339438.0 339936.0 339973.3 340828.0 68997.7 69365.3 69365.7 69443.7 69538.3 72917.3 72954.0 73002.6 73095.6 73190.0 17833.3 18312.0 18318.2 18325.9 18336.8 14027.0 14297.3 14603.7 15081.5 15271.4 19138.8 19847.3 20109.5 20121.3 20134.8 6891.0 6916.3 6927.1 6991.4 7021.1 866.1 869.6 870.7 873.2 892.0 33577.2 34288.5 34321.7 34400.7 34965.5 1076.2 1111.6 1112.3 1126.7 1131.6 17853.6 18010.6 18043.0 18096.3 18152.2 481331.0 481610.0 482883.0 483495.0 487571.0 68218.7 68580.0 68610.0 68733.7 68771.3 1495.7 1562.0 1568.6 1578.7 1591.4 32218.4 33801.2 34028.3 34153.7 34335.7 57619.7 59338.7 59443.1 59498.1 59518.0 385059.0 389295.0 391110.0 391894.0 391979.0 38694.0 39613.0 39793.8 40226.6 43076.4 45502.5 50250.7 50490.0 50729.7 50774.5 43120.5 44375.8 44805.0 44891.8 46006.0 43343.8 43818.3 44496.3 45612.8 45705.5 44376.0 46609.3 47329.8 47945.5 50965.5 45260.5 46474.5 46666.3 47344.0 48565.8 36241.3 36289.1 36377.0 36851.8 36883.8 03:23:57 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | [#2] jquery.html Cycles:180 Average:34905.08 Median:13194.75 stddev:106247.61 (805.2%) stddev-sans-first:106514.13 03:23:57 INFO - PROCESS | 27959 | Values: 432.6 445.5 453.1 458.4 465.9 20248.0 21624.8 21932.3 22136.2 22518.8 22523.8 22894.9 23138.5 23371.5 23577.1 5525.9 5686.4 5720.5 6146.8 6365.1 4339.9 6423.3 6469.7 6470.8 6473.7 4504.7 4545.5 4939.6 7025.4 7159.8 7034.3 7039.5 7171.1 7172.1 7173.0 6333.6 6366.4 6376.5 6381.1 6381.1 15055.2 15136.2 15154.8 15270.2 15550.1 2812.4 3151.3 3172.7 3186.9 3196.6 11023.6 11619.7 11653.6 14205.5 15106.2 2054.8 2056.8 2070.8 2073.3 2085.1 92140.5 92895.0 97456.5 98709.0 100418.5 11266.1 11434.0 11554.3 11560.8 11619.9 15608.4 15608.5 15618.2 15629.1 15666.7 8550.6 8700.7 8841.7 8931.0 8973.1 8883.1 8906.7 8936.6 8972.0 9165.4 4620.0 4694.7 4746.4 4778.1 5283.7 2172.8 2240.7 2249.4 2264.3 2270.1 926.2 942.7 949.1 967.0 971.4 20657.6 20854.8 20955.7 20967.7 20988.8 940.5 950.7 952.4 955.9 964.0 11463.8 11518.8 11563.2 11675.6 11695.8 635380.0 636434.0 645521.0 653958.0 658246.0 100681.5 103568.5 106391.5 106676.5 107632.0 1020.4 1069.7 1084.7 1099.2 1100.2 17834.7 18055.0 18079.7 18188.9 18445.4 45061.8 46064.8 46219.5 47336.0 48015.3 62933.0 63313.0 64638.0 64687.7 65818.7 9419.6 9539.6 9554.0 9583.7 9745.1 10294.3 12569.0 14289.0 14661.0 14841.7 12643.8 12778.6 12783.2 12908.0 12969.8 12386.9 12428.1 12487.9 12730.2 12802.9 12782.0 12829.6 12848.3 12859.7 13051.2 9575.9 9605.4 9730.2 9853.2 9980.9 12255.5 12646.1 12719.8 12943.4 12951.2 03:23:57 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | [#3] mootools.html Cycles:180 Average:5673.12 Median:5340.81 stddev:3101.41 (58.1%) stddev-sans-first:3094.44 03:23:57 INFO - PROCESS | 27959 | Values: 1523.6 1533.8 1543.0 1543.8 1544.7 8244.0 8747.1 8794.2 8802.8 8913.4 7676.7 7852.7 8410.2 8645.1 8677.0 2355.5 2397.2 2397.5 2401.9 2406.7 2370.9 2403.6 2405.6 2412.5 2413.3 2389.3 2390.4 2415.7 2420.4 2424.2 2283.0 2352.8 2371.9 2393.3 2400.5 2340.1 2341.7 2345.4 2399.1 2401.3 8485.2 8510.1 8605.5 8606.6 8637.9 5213.3 5336.4 5345.2 5353.4 5355.2 3400.1 3403.0 3447.8 3466.8 3489.7 1372.6 1377.3 1379.6 1426.5 1433.6 11174.5 11184.0 11188.3 11202.2 11228.5 4492.3 4867.9 4907.1 4915.6 4923.5 9895.0 9987.2 9991.0 10008.3 10039.6 5485.9 5494.9 5497.7 5508.8 5511.2 4782.7 4823.0 4871.4 4911.6 5004.0 3885.2 4004.5 4046.8 4060.3 4064.4 1232.7 1233.9 1237.6 1245.9 1247.6 973.9 986.0 986.7 992.3 993.6 5682.0 5706.7 5708.9 5807.2 5817.6 597.3 604.4 618.8 625.4 639.8 3976.7 4006.5 4021.9 4035.7 4054.2 10355.2 10691.8 10716.1 10721.5 10748.1 10476.4 10632.8 10644.7 10666.3 10722.4 8051.2 8189.2 8191.9 8194.1 8205.5 5685.8 5724.7 5791.4 5805.5 5916.7 9545.5 9551.6 9564.3 9580.4 9662.8 8966.0 9176.3 9194.4 9409.3 9412.1 7168.9 7299.6 7302.6 7419.0 7526.6 8228.9 8491.8 8497.7 8544.6 8570.6 7412.1 7469.1 7481.8 7503.0 7647.2 7320.0 7344.8 7404.2 7443.4 7464.3 6617.4 6739.5 6807.2 6979.1 6979.3 5077.6 5127.7 5133.1 5260.7 5270.8 5230.9 5274.8 5367.0 5389.8 5449.2 03:23:57 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | [#4] prototype.html Cycles:180 Average:4789.37 Median:5454.62 stddev:1363.00 (25.0%) stddev-sans-first:1326.50 03:23:57 INFO - PROCESS | 27959 | Values: 404.7 417.6 418.2 428.6 429.0 5334.9 5399.0 5457.5 5461.8 5465.9 5234.4 5309.8 5365.7 5408.0 5418.5 4252.5 4269.9 4274.3 4286.4 4316.8 4029.5 4147.8 4161.2 4246.3 4277.4 4238.8 4254.2 4270.6 4275.3 4275.4 4272.3 4278.9 4279.8 4289.1 4298.4 3670.8 3744.0 3868.1 3877.0 3905.4 5179.3 5187.9 5193.4 5198.2 5230.4 4985.7 4988.9 4993.5 5001.4 5019.7 5470.9 5616.2 5623.7 5636.6 5663.9 4334.6 4342.0 4344.7 4354.3 4375.3 5571.0 5774.2 5811.4 5819.4 5823.3 4377.7 4457.9 4462.3 4477.1 4477.3 4513.9 4559.6 4563.5 4566.3 4574.6 4976.3 5019.8 5034.1 5047.2 5047.5 5386.2 5588.6 5607.3 5615.2 5653.3 1428.9 1432.6 1433.2 1439.3 1449.3 955.1 988.9 991.6 993.1 994.9 5262.8 5338.1 5357.5 5369.3 5424.7 5626.4 5707.4 5739.9 5740.6 5753.7 5486.2 5486.9 5496.0 5499.9 5514.5 2848.8 2947.3 2976.7 3023.4 3052.1 5745.8 5798.3 5836.8 5928.5 5942.0 5543.1 5700.4 5720.3 5764.3 5779.7 5768.2 5863.3 5868.6 5901.4 5906.8 5599.7 5621.9 5630.4 5632.6 5634.5 5460.5 5556.3 5767.0 5787.6 5806.9 5984.2 6026.5 6034.5 6043.5 6058.8 4223.6 4293.7 4297.6 4312.8 4323.6 5796.9 5804.7 5813.6 5875.8 5907.9 5585.7 5673.4 5681.5 5683.5 5706.1 5448.2 5639.5 5639.8 5671.0 5677.0 5654.5 5660.3 5662.0 5669.9 5684.0 5422.2 5593.7 5683.5 5691.0 5732.4 5451.8 5486.4 5520.9 5524.1 5563.4 03:23:57 INFO - PROCESS | 27959 | 03:23:57 INFO - PROCESS | 27959 | [#5] yui.html Cycles:180 Average:17723.63 Median:41133.19 stddev:21471.61 (52.2%) stddev-sans-first:21494.43 03:23:57 INFO - PROCESS | 27959 | Values: 845.1 863.4 872.4 915.0 920.3 16650.8 17249.1 17509.9 17514.7 17530.7 16865.7 17073.1 17184.0 17654.6 18146.0 810.8 845.5 850.5 851.3 851.4 719.9 734.9 819.2 839.8 852.9 899.5 901.7 902.4 904.8 906.0 888.1 888.5 888.5 888.6 888.6 552.1 566.1 632.1 642.6 658.0 17153.5 17215.2 17228.5 17228.9 17277.7 15170.4 15370.8 15402.5 15563.6 15657.4 13021.8 13291.7 13391.7 13500.6 13792.4 3962.8 3973.3 3984.6 4061.4 4073.8 62487.7 82675.0 85059.6 85275.0 85782.0 12346.9 12549.3 12570.9 12795.5 12798.7 20908.0 21029.3 21752.1 23233.7 23250.4 8617.9 8803.8 8898.4 9037.7 9053.6 4797.9 4933.4 4949.9 4982.3 5073.4 3931.6 4037.8 4052.0 4082.8 4105.2 657.0 658.2 666.4 669.8 676.9 787.3 798.5 807.8 817.1 835.4 11036.9 11284.9 11308.9 11309.5 11329.7 797.2 801.2 805.2 807.3 818.8 5160.2 5257.5 5262.5 5276.2 5283.2 59086.1 60349.7 80021.0 84121.2 84843.6 65801.7 65940.0 66626.7 67151.7 68289.0 48114.8 59683.0 60804.0 61202.4 61690.4 10899.2 11451.7 11470.5 11483.9 11621.0 41100.4 41558.8 42261.6 42680.6 42942.6 41166.0 44324.8 51719.7 51781.2 51947.7 13624.4 14033.9 14050.8 14052.0 14254.1 15761.3 15801.4 16016.6 16203.5 16993.4 13688.1 13797.8 13866.9 14659.4 14664.6 14307.6 14503.3 15006.2 15209.8 15484.7 13043.2 13346.6 13429.7 13433.3 13619.3 10688.6 10833.6 12110.5 12225.8 12678.4 11685.4 11921.9 12220.1 12323.1 12492.4 03:23:57 INFO - PROCESS | 27959 | -------- Summary: end -------- 03:23:57 INFO - PROCESS | 27959 | 03:23:58 INFO - TEST-INFO | 27959: exit 0 03:23:58 INFO - TEST-OK | dromaeo_css | took 1114080ms 03:23:58 INFO - TEST-START | kraken 03:23:58 INFO - operating with platform_type : linux_ 03:23:58 INFO - Initialising browser for kraken test... 03:23:58 INFO - TEST-INFO | started process 28276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6A1wv2/profile http://localhost:50378/getInfo.html) 03:24:02 INFO - PROCESS | 28276 | __metrics Screen width/height:1600/1200 03:24:02 INFO - PROCESS | 28276 | colorDepth:24 03:24:02 INFO - PROCESS | 28276 | Browser inner width/height: 1024/697 03:24:02 INFO - PROCESS | 28276 | __metrics 03:24:03 INFO - PROCESS | 28276 | 1486639443460 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) 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:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884: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 03:24:03 INFO - PROCESS | 28276 | 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" 03:24:03 INFO - PROCESS | 28276 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:24:04 INFO - TEST-INFO | 28276: exit 0 03:24:04 INFO - Browser initialized. 03:24:04 INFO - Running cycle 1/1 for kraken test... 03:24:04 INFO - TEST-INFO | started process 28369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6A1wv2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:24:16 INFO - PROCESS | 28369 | RSS: Main: 137400320 03:24:16 INFO - PROCESS | 28369 | 03:24:18 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50378/tests/kraken/driver.html?testName=audio-beat-detection) 03:24:19 INFO - PROCESS | 28369 | RSS: Main: 182231040 03:24:19 INFO - PROCESS | 28369 | 03:24:22 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50378/tests/kraken/driver.html?testName=audio-dft) 03:24:22 INFO - PROCESS | 28369 | RSS: Main: 172376064 03:24:22 INFO - PROCESS | 28369 | 03:24:25 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50378/tests/kraken/driver.html?testName=audio-fft) 03:24:26 INFO - PROCESS | 28369 | RSS: Main: 264028160 03:24:26 INFO - PROCESS | 28369 | 03:24:28 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50378/tests/kraken/driver.html?testName=audio-oscillator) 03:24:28 INFO - PROCESS | 28369 | RSS: Main: 170438656 03:24:28 INFO - PROCESS | 28369 | 03:24:30 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50378/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:24:31 INFO - PROCESS | 28369 | RSS: Main: 172789760 03:24:31 INFO - PROCESS | 28369 | 03:24:34 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50378/tests/kraken/driver.html?testName=imaging-darkroom) 03:24:35 INFO - PROCESS | 28369 | RSS: Main: 210853888 03:24:35 INFO - PROCESS | 28369 | 03:24:38 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50378/tests/kraken/driver.html?testName=imaging-desaturate) 03:24:39 INFO - PROCESS | 28369 | RSS: Main: 209666048 03:24:39 INFO - PROCESS | 28369 | 03:24:41 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50378/tests/kraken/driver.html?testName=json-parse-financial) 03:24:42 INFO - PROCESS | 28369 | RSS: Main: 208076800 03:24:42 INFO - PROCESS | 28369 | 03:24:43 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50378/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:24:44 INFO - PROCESS | 28369 | RSS: Main: 170598400 03:24:44 INFO - PROCESS | 28369 | 03:24:46 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50378/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:24:46 INFO - PROCESS | 28369 | RSS: Main: 178298880 03:24:46 INFO - PROCESS | 28369 | 03:24:48 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50378/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:24:49 INFO - PROCESS | 28369 | RSS: Main: 187224064 03:24:49 INFO - PROCESS | 28369 | 03:24:51 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50378/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:24:52 INFO - PROCESS | 28369 | RSS: Main: 195633152 03:24:52 INFO - PROCESS | 28369 | 03:24:55 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50378/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:24:55 INFO - PROCESS | 28369 | RSS: Main: 192610304 03:24:55 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | Cycle 1(1): loaded http://localhost:50378/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50378/tests/kraken/driver.html?testName=ai-astar) 03:24:57 INFO - PROCESS | 28369 | RSS: Main: 244006912 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | __start_tp_report 03:24:57 INFO - PROCESS | 28369 | _x_x_mozilla_page_load 03:24:57 INFO - PROCESS | 28369 | _x_x_mozilla_page_load_details 03:24:57 INFO - PROCESS | 28369 | |i|pagename|runs| 03:24:57 INFO - PROCESS | 28369 | |0;ai-astar;109;113;112;111;112;113;112;111;111;112 03:24:57 INFO - PROCESS | 28369 | |1;audio-beat-detection;145;118;120;148;119;120;118;117;137;118 03:24:57 INFO - PROCESS | 28369 | |2;audio-dft;161;160;160;161;161;160;160;159;161;160 03:24:57 INFO - PROCESS | 28369 | |3;audio-fft;75;76;75;109;75;76;75;75;90;75 03:24:57 INFO - PROCESS | 28369 | |4;audio-oscillator;83;82;107;82;84;83;102;84;83;103 03:24:57 INFO - PROCESS | 28369 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;117;117 03:24:57 INFO - PROCESS | 28369 | |6;imaging-darkroom;147;147;147;147;147;146;148;147;147;147 03:24:57 INFO - PROCESS | 28369 | |7;imaging-desaturate;96;96;96;95;95;95;95;95;96;96 03:24:57 INFO - PROCESS | 28369 | |8;json-parse-financial;48;48;50;48;48;52;49;48;49;49 03:24:57 INFO - PROCESS | 28369 | |9;json-stringify-tinderbox;64;64;63;65;64;63;64;63;64;65 03:24:57 INFO - PROCESS | 28369 | |10;stanford-crypto-aes;76;75;76;82;76;77;85;75;75;79 03:24:57 INFO - PROCESS | 28369 | |11;stanford-crypto-ccm;124;121;122;121;120;137;129;119;153;124 03:24:57 INFO - PROCESS | 28369 | |12;stanford-crypto-pbkdf2;163;161;159;157;160;164;159;159;159;162 03:24:57 INFO - PROCESS | 28369 | |13;stanford-crypto-sha256-iterative;62;61;61;64;64;61;62;60;60;61 03:24:57 INFO - PROCESS | 28369 | __end_tp_report 03:24:57 INFO - PROCESS | 28369 | __start_cc_report 03:24:57 INFO - PROCESS | 28369 | _x_x_mozilla_cycle_collect,1535 03:24:57 INFO - PROCESS | 28369 | __end_cc_report 03:24:57 INFO - PROCESS | 28369 | __startTimestamp1486639497836__endTimestamp 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | ------- Summary: start ------- 03:24:57 INFO - PROCESS | 28369 | Number of tests: 14 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:1.17 (1.0%) stddev-sans-first:0.78 03:24:57 INFO - PROCESS | 28369 | Values: 109.0 113.0 112.0 111.0 112.0 113.0 112.0 111.0 111.0 112.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:120.00 stddev:12.29 (10.2%) stddev-sans-first:10.95 03:24:57 INFO - PROCESS | 28369 | Values: 145.0 118.0 120.0 148.0 119.0 120.0 118.0 117.0 137.0 118.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 03:24:57 INFO - PROCESS | 28369 | Values: 161.0 160.0 160.0 161.0 161.0 160.0 160.0 159.0 161.0 160.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#3] audio-fft Cycles:10 Average:80.10 Median:75.00 stddev:11.17 (14.9%) stddev-sans-first:11.69 03:24:57 INFO - PROCESS | 28369 | Values: 75.0 76.0 75.0 109.0 75.0 76.0 75.0 75.0 90.0 75.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:83.00 stddev:10.24 (12.3%) stddev-sans-first:10.61 03:24:57 INFO - PROCESS | 28369 | Values: 83.0 82.0 107.0 82.0 84.0 83.0 102.0 84.0 83.0 103.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 03:24:57 INFO - PROCESS | 28369 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 03:24:57 INFO - PROCESS | 28369 | Values: 147.0 147.0 147.0 147.0 147.0 146.0 148.0 147.0 147.0 147.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 03:24:57 INFO - PROCESS | 28369 | Values: 96.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#8] json-parse-financial Cycles:10 Average:48.90 Median:49.00 stddev:1.29 (2.6%) stddev-sans-first:1.32 03:24:57 INFO - PROCESS | 28369 | Values: 48.0 48.0 50.0 48.0 48.0 52.0 49.0 48.0 49.0 49.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 03:24:57 INFO - PROCESS | 28369 | Values: 64.0 64.0 63.0 65.0 64.0 63.0 64.0 63.0 64.0 65.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:76.50 stddev:3.41 (4.5%) stddev-sans-first:3.56 03:24:57 INFO - PROCESS | 28369 | Values: 76.0 75.0 76.0 82.0 76.0 77.0 85.0 75.0 75.0 79.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:124.00 stddev:10.58 (8.5%) stddev-sans-first:11.17 03:24:57 INFO - PROCESS | 28369 | Values: 124.0 121.0 122.0 121.0 120.0 137.0 129.0 119.0 153.0 124.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.30 Median:160.50 stddev:2.16 (1.3%) stddev-sans-first:2.06 03:24:57 INFO - PROCESS | 28369 | Values: 163.0 161.0 159.0 157.0 160.0 164.0 159.0 159.0 159.0 162.0 03:24:57 INFO - PROCESS | 28369 | 03:24:57 INFO - PROCESS | 28369 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.50 stddev:1.43 (2.3%) stddev-sans-first:1.51 03:24:57 INFO - PROCESS | 28369 | Values: 62.0 61.0 61.0 64.0 64.0 61.0 62.0 60.0 60.0 61.0 03:24:57 INFO - PROCESS | 28369 | -------- Summary: end -------- 03:24:57 INFO - PROCESS | 28369 | 03:24:58 INFO - TEST-INFO | 28369: exit 0 03:24:58 INFO - TEST-OK | kraken | took 60274ms 03:24:58 INFO - SUITE-END | took 1174s 03:24:58 INFO - Completed test suite (00:19:34) 03:24:58 INFO - javascript benchmark 03:24:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3254.886078987235, 3287.309664970356, 3295.5386245925565, 3322.23588574628, 3342.8997130086104, 5447.1012282973315, 5503.752331145098, 5555.694436805979, 5572.554195664347, 5613.158026296058, 5515.669059856412, 5623.0, 5631.239782629344, 5642.069058605325, 5658.971705141468, 5020.39755229373, 5031.974840125794, 5065.0, 5098.796048158069, 5106.923396149042, 4894.486078961711, 4914.582260507861, 5075.9746201268945, 5125.538701516863, 5128.8974220515565, 4934.383202099738, 4968.950310496885, 4979.402471703401, 5060.797568097271, 5081.46644602317, 5072.644914855961, 5078.085944529983, 5079.492050794928, 5083.567896728782, 5102.668326558762, 4834.6374021948495, 4848.345473361086, 4980.260856528895, 4999.600031997432, 5062.164742817431, 5402.405735369106, 5451.3458384994055, 5452.454754524555, 5526.778928842841, 5583.581231407661, 5979.073243647242, 5987.341392446828, 5988.371221021774, 5993.880122397528, 5994.610350327258, 5856.1508581255475, 6015.3383127855905, 6018.729157187937, 6023.096535519663, 6036.577439579231, 5505.559555235572, 5517.613767036309, 5575.498205161555, 5590.608657394025, 5697.202391665171, 6123.357047510036, 6128.877422451526, 6145.539084568675, 6146.170267013986, 6156.722947467375, 5858.326292476419, 5878.588498805043, 5916.083007133901, 5924.792632257937, 5924.940750592439, 5846.7076646167525, 5867.325257595431, 5869.647821130746, 5870.5890587659305, 5906.143609176645, 5945.791897283574, 5953.672548009815, 5953.940460595425, 5962.493188079062, 5982.73077711504, 5931.881362372728, 5939.524838012949, 5941.881162376728, 5952.0, 5958.880822383615, 4034.2133284009337, 4052.4326594276827, 4093.038136038029, 4121.093359460914, 4124.546299907008, 3809.847606095725, 3811.1996480739354, 3829.0427393151713, 3835.347990841508, 3846.3076646203954, 5734.16854556087, 5785.4793068623185, 5787.855303617359, 5799.88400231993, 5801.9129713054335, 5762.4525670061275, 5859.0, 5953.315368732651, 5975.163477113208, 5987.251593550806, 5800.50695690871, 5802.21670074543, 5806.187133801272, 5806.68063256525, 5810.0, 5128.25640282157, 5133.178691409356, 5134.691918484903, 5138.717370544656, 5169.172932330809, 6057.030875059969, 6087.999999999912, 6098.664573448503, 6112.17485639433, 6255.78104766331, 5992.730327135377, 5994.400559944145, 6011.639301641915, 6015.48868346178, 6015.48868346178, 6195.101710252078, 6204.286507051566, 6205.751769929152, 6209.689515524296, 6217.253929528486, 5692.971535142464, 5717.285339332583, 5740.512056475246, 5858.5898987070495, 5864.882702346014, 5798.188253644409, 5836.212111365081, 5836.708164591669, 5923.318818335774, 5943.494802941798, 5757.05008673581, 5759.712014399347, 5761.91357129635, 5765.625234359753, 5767.163761254511, 5629.746661400329, 5737.540996720169, 5769.07694768835, 5773.191753154646, 5774.307083150049, 5715.885682286248, 5726.656400615976, 5747.3103227612955, 5758.021136406905, 5809.970950145223, 5849.0, 5853.94146058534, 5854.590178687451, 5858.47273745365, 5870.442307980735, 5697.57268204878, 5709.8287051388525, 5717.056685646821, 5721.5136713379825, 5721.542276617777], "value": 5460.500601967793, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.2668886681856, 585.9794907178248, 620.2061361457336, 645.8127143128493, 686.9141357330334, 39274.214515709704, 47195.292070618925, 48099.51900480994, 48100.03799924004, 53653.195202072, 51128.0, 51589.0, 51623.74188129063, 51693.0, 51704.48295517053, 8736.126387361277, 9169.633214671421, 9389.342746007782, 9407.81184376312, 9414.670486532972, 8697.652093916236, 10840.295380800262, 11059.0, 11061.391623460706, 11455.198136130473, 11263.831042534368, 11326.150538709588, 11328.716782080473, 11338.546458141664, 11341.943290283538, 11069.391183484906, 11117.777044525095, 11124.833127503094, 11139.554417823278, 11140.33158010522, 11093.72265693356, 11151.107911367071, 11155.38645374504, 11155.553777848876, 11161.888381116247, 46325.53674463246, 46415.76792116018, 46665.53334466646, 46689.53310466886, 46746.0, 4365.847195348179, 4397.0, 4432.20220360335, 4499.932501012488, 4513.7743112844555, 35006.29987400263, 35071.4739278911, 36500.63499365025, 36512.452313215326, 37015.81492092549, 4583.610393116589, 4666.346711460398, 4666.719996800169, 4713.151632706112, 4716.811327546894, 338437.0, 339438.0, 339936.0, 339973.30013350025, 340828.0, 68997.65501172462, 69365.3063469369, 69365.65317173382, 69443.65278173577, 69538.30461695419, 72917.27082729212, 72954.0, 73002.63498682472, 73095.63452182704, 73190.0, 17833.286668533114, 18312.0, 18318.175682094337, 18325.908370458063, 18336.81663183378, 14027.0, 14297.28513574338, 14603.707925841423, 15081.472148474752, 15271.389144434099, 19138.80861191398, 19847.30534431288, 20109.49726256826, 20121.295754648516, 20134.798652013586, 6890.965545172342, 6916.308369163144, 6927.134108236471, 6991.370776630026, 7021.087258656342, 866.1035827918074, 869.5869462005497, 870.6865528409768, 873.1661263493348, 891.9598618062073, 33577.153256571124, 34288.48567271493, 34321.65678343185, 34400.65599344025, 34965.452401916344, 1076.1874784537624, 1111.632223364229, 1112.2659045030402, 1126.6901602059336, 1131.620906996149, 17853.553661158316, 18010.63978720418, 18043.0, 18096.276148953893, 18152.183151758203, 481331.0, 481610.0, 482883.0, 483495.0, 487571.0, 68218.65890670515, 68580.0, 68610.0, 68733.65633171803, 68771.3122868775, 1495.723291191133, 1561.9765703514456, 1568.5843251538352, 1578.7316156253464, 1591.3714082937174, 32218.35563288674, 33801.15497112592, 34028.3194336107, 34153.65846341604, 34335.65664343325, 57619.71190144023, 59338.70330648319, 59443.108353375595, 59498.107528387976, 59518.0, 385059.0, 389295.0, 391110.0, 391894.0, 391979.0, 38694.0, 39613.0, 39793.80103099582, 40226.59773402228, 43076.35385469283, 45502.54497455116, 50250.743731407005, 50490.0, 50729.746351268004, 50774.492255078454, 43120.536401128884, 44375.778121109186, 44805.0, 44891.77554112339, 46006.0, 43343.78328108339, 43818.342724858514, 44496.33255501105, 45612.77193614011, 45705.54294457013, 44376.0, 46609.300860486444, 47329.76335118302, 47945.520544794104, 50965.49034509608, 45260.54739452563, 46474.53525464837, 46666.300005499266, 47344.0, 48565.75717121392, 36241.27517449584, 36289.092772680895, 36377.0, 36851.81574092113, 36883.815580921924], "value": 22318.872039448128, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.58687953004886, 445.50548890731284, 453.0847687670904, 458.401785669701, 465.9324397962296, 20248.0, 21624.783752162493, 21932.342029739095, 22136.225232116878, 22518.77481225187, 22523.77476225237, 22894.88552557235, 23138.537229255406, 23371.5325693486, 23577.056917723312, 5525.917111243334, 5686.431356864301, 5720.456556627135, 6146.784862529812, 6365.108884756138, 4339.913201735963, 6423.325550817167, 6469.74121035158, 6470.773522926698, 6473.708683109271, 4504.684672072956, 4545.545445455461, 4939.60483161348, 7025.437964962791, 7159.785206443815, 7034.331738484836, 7039.472039597051, 7171.103612048494, 7172.139343278814, 7173.031640728513, 6333.619982801047, 6366.395192456709, 6376.521760867953, 6381.074744162071, 6381.1385462962835, 15055.247237638183, 15136.167510786903, 15154.772678409832, 15270.16014119223, 15550.144742039183, 2812.4375124975086, 3151.306712523241, 3172.698593633601, 3186.9043928682177, 3196.568463257454, 11023.61417350397, 11619.709507262301, 11653.592124275694, 14205.502807401796, 15106.169160696158, 2054.845886558499, 2056.7737548869613, 2070.7722150563427, 2073.315805784103, 2085.0513016577574, 92140.53929730375, 92895.0, 97456.51271743666, 98709.0, 100418.49790751073, 11266.098712103012, 11434.0, 11554.306741595363, 11560.826587601192, 11619.941900290614, 15608.375664973273, 15608.531744047697, 15618.219089045502, 15629.06225626466, 15666.68666626661, 8550.615222315011, 8700.738977830675, 8841.734747957571, 8931.017588065308, 8973.147550982647, 8883.111688831064, 8906.662448467066, 8936.597853096626, 8971.955140224258, 9165.358424910191, 4619.95380046202, 4694.671373003857, 4746.406699162605, 4778.116048531033, 5283.675182919503, 2172.771858954787, 2240.6863039174527, 2249.3589327041714, 2264.29806759905, 2270.1260014894383, 926.2219735422277, 942.7266092833139, 949.1125797379473, 966.9613215471302, 971.3880255439028, 20657.586848262952, 20854.791452085286, 20955.68566471504, 20967.685484717742, 20988.790112098988, 940.501534186882, 950.7100334397936, 952.3952290295574, 955.9378640388353, 963.9903600963901, 11463.828042579367, 11518.769624607461, 11563.248388854698, 11675.591354302729, 11695.766084678258, 635380.0, 636434.0, 645521.0, 653958.0, 658246.0, 100681.49659251803, 103568.48215758873, 106391.46804266084, 106676.46661766642, 107632.0, 1020.4438580973388, 1069.7001446889094, 1084.7071290751453, 1099.2360309584762, 1100.1858624617726, 17834.732479012564, 18055.0, 18079.728804068214, 18188.909055455166, 18445.446636600922, 45061.77469112633, 46064.7696761514, 46219.537804622865, 47336.0, 48015.27977080416, 62933.0, 63313.0, 64638.0, 64687.67656161878, 65818.67090664516, 9419.62321507132, 9539.618415263312, 9554.0, 9583.664571739955, 9745.134890566067, 10294.279400441897, 12569.0, 14289.0, 14661.04703194289, 14841.703165936837, 12643.810342845049, 12778.552750653682, 12783.16909400886, 12907.999260114697, 12969.805452918403, 12386.876131238572, 12428.0678949081, 12487.875121249035, 12730.17253878495, 12802.871971280169, 12782.0, 12829.550965716155, 12848.293343866177, 12859.678508037, 13051.151675141085, 9575.904240957501, 9605.37565058269, 9730.221582273543, 9853.16248118918, 9980.900190997998, 12255.509779608716, 12646.051546133893, 12719.80920286215, 12943.352832358532, 12951.222926624056], "value": 10019.929162906266, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1523.6343277613375, 1533.846615338467, 1542.973922341643, 1543.8378970208128, 1544.6833399153172, 8244.0, 8747.12528747125, 8794.164554367333, 8802.823943521134, 8913.420627659214, 7676.654550545225, 7852.725154619589, 8410.158984101587, 8645.135486451352, 8677.0, 2355.4700192456644, 2397.2089210560475, 2397.4605713714363, 2401.927942161738, 2406.68713067301, 2370.9051637934463, 2403.6034054381007, 2405.6391541268863, 2412.469256763518, 2413.3122060212845, 2389.307100940725, 2390.40239940015, 2415.7101147862195, 2420.4070002849326, 2424.2363655448567, 2283.0, 2352.8235382346306, 2371.9288421347387, 2393.293978276404, 2400.5198960208027, 2340.1458467659354, 2341.7072865891764, 2345.44881952741, 2399.0883464283634, 2401.3156250468705, 8485.151484851527, 8510.106438823896, 8605.526696031715, 8606.612702428405, 8637.870431943526, 5213.296205012312, 5336.38631557372, 5345.224942383372, 5353.411124776271, 5355.196720491918, 3400.132966093655, 3402.9659703402895, 3447.775894566845, 3466.8266586670816, 3489.70337521311, 1372.5813626843808, 1377.3388773388829, 1379.6412932637586, 1426.4793350427124, 1433.5627633571758, 11174.497147628375, 11184.0, 11188.328700278009, 11202.21584489078, 11228.49471773764, 4492.303692927601, 4867.902641947141, 4907.116718990616, 4915.631327650441, 4923.458419573844, 9895.0, 9987.151092157103, 9991.0, 10008.349457285252, 10039.648612298535, 5485.862853428616, 5494.945050549443, 5497.670139791625, 5508.807191748349, 5511.22842802008, 4782.689125206851, 4822.9871726937035, 4871.415430148405, 4911.631627627942, 5004.0, 3885.1646895916947, 4004.4994375703036, 4046.7571945682766, 4060.3300455425115, 4064.3903414488063, 1232.6671798614325, 1233.864274929757, 1237.560665963588, 1245.850497940253, 1247.594531777158, 973.9318247722591, 985.9704208873745, 986.7187851462443, 992.3477410112226, 993.5826952679892, 5682.034054210794, 5706.686132262683, 5708.942910570924, 5807.157962095473, 5817.621854579439, 597.2922087326532, 604.3986233697499, 618.7988903606346, 625.4308579193004, 639.8112556795757, 3976.721629485966, 4006.4991876015497, 4021.93967090488, 4035.7174997749876, 4054.2499637567144, 10355.223358248162, 10691.83962240567, 10716.089132423831, 10721.517531711092, 10748.140148788078, 10476.423796691255, 10632.78734425307, 10644.733881652866, 10666.306690065121, 10722.356658600509, 8051.194880512019, 8189.22202390772, 8191.8771218430575, 8194.05768336637, 8205.507669539847, 5685.77256909719, 5724.713764311685, 5791.391903850035, 5805.477507024388, 5916.704164791657, 9545.522723863918, 9551.617935282511, 9564.282678799256, 9580.377275477073, 9662.75843103928, 8965.955170224108, 9176.265898727952, 9194.448333100036, 9409.29430292717, 9412.105849944244, 7168.8924666129005, 7299.5620262784405, 7302.56184628924, 7418.96290518544, 7526.623668816646, 8228.876566851623, 8491.78770530717, 8497.660093596434, 8544.572771361283, 8570.614322355385, 7412.073490813648, 7469.066366704162, 7481.7755467336065, 7503.0, 7647.19704431049, 7320.0, 7344.816379590339, 7404.185539590533, 7443.441741869273, 7464.328210460867, 6617.43751781104, 6739.528233023641, 6807.183138023271, 6979.092717946634, 6979.30206979298, 5077.593792496517, 5127.717975511383, 5133.050385678662, 5260.736963151904, 5270.839040669531, 5230.947690523046, 5274.76263568148, 5366.973165134149, 5389.8113566025, 5449.182622606482], "value": 4572.609593820909, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.6964776417687, 417.5532180566795, 418.178279680428, 428.5757100470535, 428.9528151903292, 5334.9466505334985, 5399.0, 5457.481539253775, 5461.808836690717, 5465.945340546603, 5234.42421333653, 5309.761060752266, 5365.739051322936, 5408.026555220059, 5418.539424148942, 4252.489701235848, 4269.850555230568, 4274.316109422493, 4286.378475121106, 4316.82732690692, 4029.4761680981433, 4147.81334839933, 4161.167766446708, 4246.341817018367, 4277.422547956017, 4238.788060596973, 4254.191703576326, 4270.572942705736, 4275.337322714984, 4275.444192255018, 4272.33778764292, 4278.914421711564, 4279.8074086665865, 4289.120730250323, 4298.398224248608, 3670.7702919521726, 3744.0, 3868.12967082407, 3876.9612303876884, 3905.3556163233034, 5179.300794392747, 5187.870303242411, 5193.350831146106, 5198.246254293144, 5230.398504172031, 4985.700857948535, 4988.850334489971, 4993.500649935014, 5001.44984051754, 5019.749012549394, 5470.863228419282, 5616.185653080322, 5623.690697011662, 5636.605437619368, 5663.915041274384, 4334.631556317717, 4341.956580434186, 4344.652427805768, 4354.346847972798, 4375.343698445258, 5571.022287088106, 5774.191613174159, 5811.447912448353, 5819.388964158787, 5823.272090988627, 4377.737335759864, 4457.866264012085, 4462.308342206963, 4477.1493416250805, 4477.34338294833, 4513.932291015637, 4559.635229181724, 4563.452385713736, 4566.26939689648, 4574.6340292776495, 4976.3127910928415, 5019.824306149268, 5034.144195486776, 5047.192449208109, 5047.520485553867, 5386.192071189274, 5588.552915766729, 5607.299087614048, 5615.157726341082, 5653.349864765604, 1428.899747194675, 1432.6131944375168, 1433.197409450711, 1439.3163247457583, 1449.289847974485, 955.0878910640322, 988.9307748457683, 991.613270824379, 993.1111655068673, 994.9006347985517, 5262.815801446931, 5338.092524270805, 5357.464253574612, 5369.275147855068, 5424.728763561806, 5626.381098079208, 5707.3721890592005, 5739.909417210717, 5740.56945729072, 5753.712314384264, 5486.231927530149, 5486.91769623456, 5496.0, 5499.945000550023, 5514.531264842453, 2848.800583959103, 2947.263184203949, 2976.687447817991, 3023.365093330381, 3052.084374687585, 5745.839788116519, 5798.333191682926, 5836.7957121501395, 5928.525717942554, 5941.970290148522, 5543.113101903676, 5700.37295897451, 5720.342160651495, 5764.308283006066, 5779.739911703983, 5768.202421825496, 5863.2670916135485, 5868.620874094499, 5901.3508514063415, 5906.763729450774, 5599.664020158803, 5621.859453513532, 5630.395337328841, 5632.605717599728, 5634.492895639412, 5460.481254280837, 5556.305057136959, 5767.0, 5787.56593255516, 5806.883862322815, 5984.16221728967, 6026.457618814327, 6034.487068599218, 6043.456088952015, 6058.84852878664, 4223.640990515718, 4293.742375457483, 4297.5702429756775, 4312.7627980461375, 4323.589259020388, 5796.898659217299, 5804.738786754522, 5813.622114562709, 5875.823725288248, 5907.881842363043, 5585.692786896597, 5673.404292549223, 5681.517071049007, 5683.5453163747625, 5706.087026075808, 5448.182772584144, 5639.492445679908, 5639.774409023593, 5670.950874088307, 5677.0, 5654.519365853784, 5660.29246344207, 5662.0, 5669.914951275651, 5684.010596291265, 5422.15956526739, 5593.692346920959, 5683.5453163747625, 5691.032524470767, 5732.426757324234, 5451.754671039734, 5486.423925487767, 5520.91718624229, 5524.06090964545, 5563.415841336601], "value": 4372.726594865763, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.0704225352114, 863.4430792139067, 872.4111224923175, 914.9954250228748, 920.295973580211, 16650.750238746412, 17249.13754312284, 17509.91245043773, 17514.737278940793, 17530.737038944426, 16865.74701379477, 17073.060981646006, 17184.0, 17654.558636034104, 18146.0, 810.7608255564593, 845.5391811462767, 850.4684572142411, 851.3019324154196, 851.4167795060404, 719.8992141100224, 734.8750712378882, 819.1849110135394, 839.7606682095604, 852.8678054901469, 899.4648184330313, 901.7024381953972, 902.4269588811119, 904.8190361927608, 906.0, 888.0897080492482, 888.4802390601506, 888.5157589113916, 888.5646033443599, 888.5779254853958, 552.0890530624501, 566.1140315406376, 632.1339764522634, 642.5758999060645, 657.9539432239745, 17153.506542689494, 17215.225314860738, 17228.483145505656, 17228.91385543077, 17277.740833887503, 15170.393184272616, 15370.769438458432, 15402.460913868073, 15563.610909727235, 15657.373705051785, 13021.804672929818, 13291.734165316639, 13391.732165356736, 13500.594982150551, 13792.379342929593, 3962.8018599069933, 3973.264945985002, 3984.621460961204, 4061.431399604058, 4073.7963101845085, 62487.68756156236, 82675.0, 85059.57470212672, 85275.0, 85782.0, 12346.938265308796, 12549.309787961567, 12570.87429125697, 12795.488180472677, 12798.68003299925, 20908.0, 21029.263975760776, 21752.129914803547, 23233.651495227587, 23250.418739531648, 8617.913820861711, 8803.779905502413, 8898.377113602115, 9037.728868133967, 9053.637854485878, 4797.856064318076, 4933.407991041026, 4949.876253093701, 4982.252662100714, 5073.365829271341, 3931.6068393160454, 4037.818298176589, 4052.0, 4082.795860206978, 4105.158442519277, 657.0308794528033, 658.203573675848, 666.4135560706547, 669.8191488298132, 676.9323067693191, 787.3307688464873, 798.4849771897094, 807.8125156020641, 817.1052697296451, 835.3776436554806, 11036.889631103746, 11284.887151128383, 11308.886911130783, 11309.49107290174, 11329.71675708114, 797.1749239537168, 801.1867954026668, 805.2350267246139, 807.2734538914906, 818.7871153500133, 5160.174372100446, 5257.4742525747115, 5262.526372626482, 5276.208568714724, 5283.181106928432, 59086.11370829441, 60349.69825150846, 80021.0, 84121.15878841134, 84843.57578212193, 65801.67099164569, 65940.0, 66626.66686666633, 67151.66424167847, 68288.99999999901, 48114.75942620265, 59683.0, 60804.0, 61202.38797612056, 61690.38309616936, 10899.237053406185, 11451.656450306504, 11470.483828227583, 11483.942580287045, 11620.954114129769, 41100.383494248206, 41558.79220603878, 42261.57738422699, 42680.5731942689, 42942.57057429511, 41166.0, 44324.77837610791, 51719.741401292755, 51781.223281650055, 51947.74026129845, 13624.386902589606, 14033.859661403256, 14050.78923816164, 14052.0, 14254.144751314954, 15761.290741916411, 15801.36794528206, 16016.599585010003, 16203.513894583182, 16993.40523081686, 13688.110272832235, 13797.793033104712, 13866.930665346608, 14659.413623454942, 14664.560063198121, 14307.64230894236, 14503.347349369515, 15006.249687515363, 15209.771853421986, 15484.690306194037, 13043.217406955613, 13346.599602011955, 13429.731405371642, 13433.26117063571, 13619.319034048456, 10688.625898093529, 10833.566657333618, 12110.45502952387, 12225.755484890431, 12678.366081696062, 11685.386524698632, 11921.940390297994, 12220.083493738182, 12323.075769317158, 12492.43784029739], "value": 7684.367216792218, "unit": "score"}], "name": "dromaeo_css", "value": 7566.419979380105, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 112.0, 111.0, 112.0, 113.0, 112.0, 111.0, 111.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 120.0, 148.0, 119.0, 120.0, 118.0, 117.0, 137.0, 118.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 161.0, 160.0, 160.0, 159.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 109.0, 75.0, 76.0, 75.0, 75.0, 90.0, 75.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 107.0, 82.0, 84.0, 83.0, 102.0, 84.0, 83.0, 103.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 148.0, 147.0, 147.0, 147.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 50.0, 48.0, 48.0, 52.0, 49.0, 48.0, 49.0, 49.0], "value": 48.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 65.0, 64.0, 63.0, 64.0, 63.0, 64.0, 65.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 82.0, 76.0, 77.0, 85.0, 75.0, 75.0, 79.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 121.0, 122.0, 121.0, 120.0, 137.0, 129.0, 119.0, 153.0, 124.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 159.0, 157.0, 160.0, 164.0, 159.0, 159.0, 159.0, 162.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 64.0, 64.0, 61.0, 62.0, 60.0, 60.0, 61.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1465.9999999999998, "alertThreshold": 2.0}]} 03:24:59 INFO - Return code: 0 03:24:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:24:59 ERROR - # TBPL SUCCESS # 03:24:59 INFO - Running post-action listener: _resource_record_post_action 03:24:59 INFO - [mozharness: 2017-02-09 11:24:59.141199Z] Finished run-tests step (success) 03:24:59 INFO - Running post-run listener: _resource_record_post_run 03:24:59 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 366211072; Read time: 0; Write time: 210380 03:24:59 INFO - TinderboxPrint: CPU usage
13.0% 03:24:59 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 03:24:59 INFO - TinderboxPrint: I/O write bytes / time
366,211,072 / 210,380 03:24:59 INFO - TinderboxPrint: CPU idle
8,269.2 (87.0%) 03:24:59 INFO - TinderboxPrint: CPU user
1,191.9 (12.5%) 03:24:59 INFO - TinderboxPrint: Swap in / out
0 / 0 03:24:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112480256; Read time: 0; Write time: 66444 03:24:59 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 252760064; Read time: 0; Write time: 143908 03:24:59 INFO - Running post-run listener: _upload_blobber_files 03:24:59 INFO - Blob upload gear active. 03:24:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:24:59 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 03:24:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:25:00 INFO - (blobuploader) - INFO - Open directory for files ... 03:25:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:25:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:25:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:01 INFO - (blobuploader) - INFO - Done attempting. 03:25:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:25:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:25:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:02 INFO - (blobuploader) - INFO - Done attempting. 03:25:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:25:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:25:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:05 INFO - (blobuploader) - INFO - Done attempting. 03:25:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:25:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:25:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:06 INFO - (blobuploader) - INFO - Done attempting. 03:25:06 INFO - (blobuploader) - INFO - Iteration through files over. 03:25:06 INFO - Return code: 0 03:25:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:25:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:25:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ff8bae65a695858fb4e313a13ac8f56e513932caf5d6c61455fc31cd01689ffdae9545bd5a13e6c7712ef1aaeadf114fa694506276d2cd2ad4917e09c419b67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18c4cbe54ef0055a129ced042d5f5a03e457f6b8d7ce64ac02a29870dfb939a5e8eb802ae82c12ad6d2f603d71a5d81119b5e99da5e06ad1726bbe80fbd8af69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52537236fda8a2cccbeadc9b58c59988fa9be9248f70b0db27a23ed9ac4ef8cf18ef791b7ec598186e799e70f331e53b18adff6b32e645ba677da243dd2fc279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c0b25290ad852de115a0177db54f6b9250a0eac7e71865ab1c2300e7d97838e4cfb3c76fbefde545619447303b00092eba7a26e0f6520056078b64bb55e0d2"} 03:25:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:25:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:25:06 INFO - Contents: 03:25:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ff8bae65a695858fb4e313a13ac8f56e513932caf5d6c61455fc31cd01689ffdae9545bd5a13e6c7712ef1aaeadf114fa694506276d2cd2ad4917e09c419b67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18c4cbe54ef0055a129ced042d5f5a03e457f6b8d7ce64ac02a29870dfb939a5e8eb802ae82c12ad6d2f603d71a5d81119b5e99da5e06ad1726bbe80fbd8af69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52537236fda8a2cccbeadc9b58c59988fa9be9248f70b0db27a23ed9ac4ef8cf18ef791b7ec598186e799e70f331e53b18adff6b32e645ba677da243dd2fc279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c0b25290ad852de115a0177db54f6b9250a0eac7e71865ab1c2300e7d97838e4cfb3c76fbefde545619447303b00092eba7a26e0f6520056078b64bb55e0d2"} 03:25:06 INFO - Running post-run listener: copy_logs_to_upload_dir 03:25:06 INFO - Copying logs to upload dir... 03:25:06 INFO - mkdir: /builds/slave/test/build/upload/logs 03:25:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.177198 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-09 03:25:06.025046) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 03:25:06.028423) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ff8bae65a695858fb4e313a13ac8f56e513932caf5d6c61455fc31cd01689ffdae9545bd5a13e6c7712ef1aaeadf114fa694506276d2cd2ad4917e09c419b67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18c4cbe54ef0055a129ced042d5f5a03e457f6b8d7ce64ac02a29870dfb939a5e8eb802ae82c12ad6d2f603d71a5d81119b5e99da5e06ad1726bbe80fbd8af69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52537236fda8a2cccbeadc9b58c59988fa9be9248f70b0db27a23ed9ac4ef8cf18ef791b7ec598186e799e70f331e53b18adff6b32e645ba677da243dd2fc279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c0b25290ad852de115a0177db54f6b9250a0eac7e71865ab1c2300e7d97838e4cfb3c76fbefde545619447303b00092eba7a26e0f6520056078b64bb55e0d2"} build_url:https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011353 build_url: 'https://queue.taskcluster.net/v1/task/CWYBlEVpSvecawukjSsUTw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ff8bae65a695858fb4e313a13ac8f56e513932caf5d6c61455fc31cd01689ffdae9545bd5a13e6c7712ef1aaeadf114fa694506276d2cd2ad4917e09c419b67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18c4cbe54ef0055a129ced042d5f5a03e457f6b8d7ce64ac02a29870dfb939a5e8eb802ae82c12ad6d2f603d71a5d81119b5e99da5e06ad1726bbe80fbd8af69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52537236fda8a2cccbeadc9b58c59988fa9be9248f70b0db27a23ed9ac4ef8cf18ef791b7ec598186e799e70f331e53b18adff6b32e645ba677da243dd2fc279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c0b25290ad852de115a0177db54f6b9250a0eac7e71865ab1c2300e7d97838e4cfb3c76fbefde545619447303b00092eba7a26e0f6520056078b64bb55e0d2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 03:25:06.052803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:25:06.053129) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1486638268.490420-774924640 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:25:06.138916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 03:25:06.139260) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 03:25:06.139576) ========= ========= Total master_lag: 0.17 =========