builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1491472809.16 results: success (0) revision: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:09.159820) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:09.160311) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:09.160621) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:09.175635) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:09.175939) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-06 03:00:09-- 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% 52.6M=0s 2017-04-06 03:00:09 (52.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088332 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:09.278082) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:09.278412) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020151 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:09.327411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 03:00:09.327772) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 --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 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-06 03:00:09,888 truncating revision to first 12 chars 2017-04-06 03:00:09,888 Setting DEBUG logging. 2017-04-06 03:00:09,888 attempt 1/10 2017-04-06 03:00:09,889 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/632a3c9e2b4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 03:00:11,192 unpacking tar archive at: mozilla-aurora-632a3c9e2b4b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.596279 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 03:00:10.937332) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:10.937661) ========= script_repo_revision: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:10.938019) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:10.938278) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 03:00:10.947888) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-04-06 03:00:10.948170) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:00:11 INFO - MultiFileLogger online at 20170406 03:00:11 in /builds/slave/test 03:00:11 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:00:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:00:11 INFO - {'append_to_log': False, 03:00:11 INFO - 'base_work_dir': '/builds/slave/test', 03:00:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:00:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:00:11 INFO - 'branch': 'Mozilla-Aurora', 03:00:11 INFO - 'buildbot_json_path': 'buildprops.json', 03:00:11 INFO - 'config_files': ('talos/linux_config.py',), 03:00:11 INFO - 'default_actions': ('clobber', 03:00:11 INFO - 'read-buildbot-config', 03:00:11 INFO - 'download-and-extract', 03:00:11 INFO - 'populate-webroot', 03:00:11 INFO - 'create-virtualenv', 03:00:11 INFO - 'install', 03:00:11 INFO - 'run-tests'), 03:00:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:00:11 INFO - 'download_minidump_stackwalk': True, 03:00:11 INFO - 'download_symbols': 'ondemand', 03:00:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:00:11 INFO - 'tooltool.py': '/tools/tooltool.py', 03:00:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:00:11 INFO - '/tools/misc-python/virtualenv.py')}, 03:00:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:00:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:00:11 INFO - 'gecko_profile': False, 03:00:11 INFO - 'gecko_profile_interval': 0, 03:00:11 INFO - 'installer_path': 'installer.exe', 03:00:11 INFO - 'log_level': 'info', 03:00:11 INFO - 'log_name': 'talos', 03:00:11 INFO - 'log_to_console': True, 03:00:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:00:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:00:11 INFO - 'opt_config_files': (), 03:00:11 INFO - 'pip_index': False, 03:00:11 INFO - 'suite': 'dromaeojs', 03:00:11 INFO - 'system_bits': '32', 03:00:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:00:11 INFO - 'title': 'talos-linux64-ix-069', 03:00:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:00:11 INFO - 'use_talos_json': True, 03:00:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:00:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:00:11 INFO - 'work_dir': 'build'} 03:00:11 INFO - [mozharness: 2017-04-06 10:00:11.609264Z] Running clobber step. 03:00:11 INFO - Running pre-action listener: _resource_record_pre_action 03:00:11 INFO - Running main action method: clobber 03:00:11 INFO - rmtree: /builds/slave/test/build 03:00:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:00:13 INFO - Running post-action listener: _resource_record_post_action 03:00:13 INFO - [mozharness: 2017-04-06 10:00:13.055664Z] Finished clobber step (success) 03:00:13 INFO - [mozharness: 2017-04-06 10:00:13.055763Z] Running read-buildbot-config step. 03:00:13 INFO - Running pre-action listener: _resource_record_pre_action 03:00:13 INFO - Running main action method: read_buildbot_config 03:00:13 INFO - Using buildbot properties: 03:00:13 INFO - { 03:00:13 INFO - "project": "", 03:00:13 INFO - "product": "firefox", 03:00:13 INFO - "who": "jlorenzo@mozilla.com", 03:00:13 INFO - "installer_path": "public/build/target.tar.bz2", 03:00:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:00:13 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 03:00:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:00:13 INFO - "stage_platform": "linux64-pgo", 03:00:13 INFO - "basedir": "/builds/slave/test", 03:00:13 INFO - "buildnumber": 873, 03:00:13 INFO - "platform": "ubuntu64_hw", 03:00:13 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:00:13 INFO - "slavebuilddir": "test", 03:00:13 INFO - "taskId": "OxpPSxYMQlWS7T8dOCOKQQ", 03:00:13 INFO - "branch": "mozilla-aurora", 03:00:13 INFO - "script_repo_revision": "production", 03:00:13 INFO - "revision": "632a3c9e2b4b0dda89ca5924c616568af8c0dd85", 03:00:13 INFO - "slavename": "talos-linux64-ix-069", 03:00:13 INFO - "repo_path": "releases/mozilla-aurora" 03:00:13 INFO - } 03:00:13 INFO - Finding installer, test and symbols from parent task. 03:00:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OxpPSxYMQlWS7T8dOCOKQQ'}, attempt #1 03:00:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OxpPSxYMQlWS7T8dOCOKQQ'}, attempt #1 03:00:13 INFO - Task dependencies: NBjVbMfURpieEOnuOoiP2w 03:00:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w'}, attempt #1 03:00:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.tar.bz2 03:00:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/test_packages.json 03:00:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.crashreporter-symbols.zip 03:00:14 INFO - Running post-action listener: _resource_record_post_action 03:00:14 INFO - [mozharness: 2017-04-06 10:00:14.408730Z] Finished read-buildbot-config step (success) 03:00:14 INFO - [mozharness: 2017-04-06 10:00:14.408946Z] Running download-and-extract step. 03:00:14 INFO - Running pre-action listener: _resource_record_pre_action 03:00:14 INFO - Running main action method: download_and_extract 03:00:14 INFO - mkdir: /builds/slave/test/build/tests 03:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:14 INFO - trying https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.test_packages.json 03:00:14 INFO - Downloading https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:00:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:00:15 INFO - Downloaded 896 bytes. 03:00:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:00:15 INFO - Using the following test package requirements: 03:00:15 INFO - {u'common': [u'target.common.tests.zip'], 03:00:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:00:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:00:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:00:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:00:15 INFO - u'mozbase': [u'target.common.tests.zip'], 03:00:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:00:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:00:15 INFO - u'web-platform': [u'target.common.tests.zip', 03:00:15 INFO - u'target.web-platform.tests.zip'], 03:00:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:00:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:00:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.common.tests.zip 03:00:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:00:15 INFO - Fetch https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.common.tests.zip into memory 03:00:17 INFO - Expected file size: 59550784 03:00:17 INFO - Obtained file size: 59550784 03:00:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:00:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.common.tests.zip 03:00:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:00:27 INFO - Fetch https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.common.tests.zip into memory 03:00:28 INFO - Expected file size: 59550784 03:00:28 INFO - Obtained file size: 59550784 03:00:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.talos.tests.zip 03:00:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:00:38 INFO - Fetch https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.talos.tests.zip into memory 03:00:40 INFO - Expected file size: 14404438 03:00:40 INFO - Obtained file size: 14404438 03:00:40 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:00:40 INFO - trying https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.tar.bz2 03:00:40 INFO - Downloading https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:00:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:00:42 INFO - Downloaded 66749416 bytes. 03:00:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.tar.bz2 03:00:42 INFO - mkdir: /builds/slave/test/properties 03:00:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:00:42 INFO - Writing to file /builds/slave/test/properties/build_url 03:00:42 INFO - Contents: 03:00:42 INFO - build_url:https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.tar.bz2 03:00:42 INFO - Running post-action listener: _resource_record_post_action 03:00:42 INFO - Running post-action listener: set_extra_try_arguments 03:00:42 INFO - [mozharness: 2017-04-06 10:00:42.941063Z] Finished download-and-extract step (success) 03:00:42 INFO - [mozharness: 2017-04-06 10:00:42.941158Z] Running populate-webroot step. 03:00:42 INFO - Running pre-action listener: _resource_record_pre_action 03:00:42 INFO - Running main action method: populate_webroot 03:00:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:00:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:00:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:00:42 INFO - '--tppagecycles', 03:00:42 INFO - '7'], 03:00:42 INFO - 'tests': ['tsvgm']}}, 03:00:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:00:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:00:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:00:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:00:42 INFO - 'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:00:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:00:42 INFO - 'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['damp', 'tps']}, 03:00:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:00:42 INFO - 'tests': ['damp', 'tps']}, 03:00:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['dromaeo_dom']}, 03:00:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['basic_compositor_video']}, 03:00:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:00:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['a11yr', 03:00:42 INFO - 'ts_paint', 03:00:42 INFO - 'tpaint', 03:00:42 INFO - 'sessionrestore', 03:00:42 INFO - 'sessionrestore_no_auto_restore', 03:00:42 INFO - 'tabpaint']}, 03:00:42 INFO - 'other-e10s': {'tests': ['a11yr', 03:00:42 INFO - 'ts_paint', 03:00:42 INFO - 'tpaint', 03:00:42 INFO - 'sessionrestore', 03:00:42 INFO - 'sessionrestore_no_auto_restore', 03:00:42 INFO - 'tabpaint']}, 03:00:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:00:42 INFO - 'ts_paint', 03:00:42 INFO - 'tpaint', 03:00:42 INFO - 'sessionrestore', 03:00:42 INFO - 'sessionrestore_no_auto_restore', 03:00:42 INFO - 'tabpaint']}, 03:00:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:00:42 INFO - 'ts_paint', 03:00:42 INFO - 'tpaint', 03:00:42 INFO - 'sessionrestore', 03:00:42 INFO - 'sessionrestore_no_auto_restore', 03:00:42 INFO - 'tabpaint']}, 03:00:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['a11yr', 03:00:42 INFO - 'ts_paint', 03:00:42 INFO - 'tpaint', 03:00:42 INFO - 'sessionrestore', 03:00:42 INFO - 'sessionrestore_no_auto_restore', 03:00:42 INFO - 'tabpaint']}, 03:00:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['a11yr', 03:00:42 INFO - 'ts_paint', 03:00:42 INFO - 'tpaint', 03:00:42 INFO - 'sessionrestore', 03:00:42 INFO - 'sessionrestore_no_auto_restore', 03:00:42 INFO - 'tabpaint']}, 03:00:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['tsvgx', 03:00:42 INFO - 'tsvgr_opacity', 03:00:42 INFO - 'tart', 03:00:42 INFO - 'tscrollx', 03:00:42 INFO - 'cart', 03:00:42 INFO - 'tsvg_static']}, 03:00:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:00:42 INFO - 'tsvgr_opacity', 03:00:42 INFO - 'tart', 03:00:42 INFO - 'tscrollx', 03:00:42 INFO - 'cart', 03:00:42 INFO - 'tsvg_static']}, 03:00:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:00:42 INFO - 'talos_options': ['--disable-e10s'], 03:00:42 INFO - 'tests': ['tp5o']}, 03:00:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:00:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:00:42 INFO - 'talos_options': ['--disable-e10s', 03:00:42 INFO - '--xperf_path', 03:00:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:42 INFO - 'tests': ['tp5n']}, 03:00:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:00:42 INFO - 'talos_options': ['--xperf_path', 03:00:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:42 INFO - 'tests': ['tp5n']}}, 03:00:42 INFO - 'talos.zip': {'path': '', 03:00:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:00:42 INFO - Running post-action listener: _resource_record_post_action 03:00:42 INFO - [mozharness: 2017-04-06 10:00:42.954167Z] Finished populate-webroot step (success) 03:00:42 INFO - [mozharness: 2017-04-06 10:00:42.954256Z] Running create-virtualenv step. 03:00:42 INFO - Running pre-action listener: _resource_record_pre_action 03:00:42 INFO - Running main action method: create_virtualenv 03:00:42 INFO - Creating virtualenv /builds/slave/test/build/venv 03:00:42 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:00:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:00:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:43 INFO - Using real prefix '/usr' 03:00:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:00:43 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:45 INFO - Installing pip.................done. 03:00:45 INFO - Return code: 0 03:00:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:00:45 INFO - Reading from file tmpfile_stdout 03:00:45 INFO - Output received: 03:00:45 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:00:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:45 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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:00:45 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:00:45 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:00:45 INFO - Using env: {'DISPLAY': ':0', 03:00:45 INFO - 'HOME': '/home/cltbld', 03:00:45 INFO - 'LANG': 'en_US.UTF-8', 03:00:45 INFO - 'LANGUAGE': 'en_US:en', 03:00:45 INFO - 'LOGNAME': 'cltbld', 03:00:45 INFO - 'MAIL': '/var/mail/cltbld', 03:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:45 INFO - 'MOZ_NO_REMOTE': '1', 03:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:45 INFO - 'NO_EM_RESTART': '1', 03:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:45 INFO - 'PWD': '/builds/slave/test', 03:00:45 INFO - 'SHELL': '/bin/bash', 03:00:45 INFO - 'SHLVL': '1', 03:00:45 INFO - 'TERM': 'linux', 03:00:45 INFO - 'TMOUT': '86400', 03:00:45 INFO - 'USER': 'cltbld', 03:00:45 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:45 INFO - '_': '/tools/buildbot/bin/python'} 03:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:45 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:00:45 INFO - Cleaning up... 03:00:45 INFO - Return code: 0 03:00:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:45 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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:00:45 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:00:45 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:00:45 INFO - Using env: {'DISPLAY': ':0', 03:00:45 INFO - 'HOME': '/home/cltbld', 03:00:45 INFO - 'LANG': 'en_US.UTF-8', 03:00:45 INFO - 'LANGUAGE': 'en_US:en', 03:00:45 INFO - 'LOGNAME': 'cltbld', 03:00:45 INFO - 'MAIL': '/var/mail/cltbld', 03:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:45 INFO - 'MOZ_NO_REMOTE': '1', 03:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:45 INFO - 'NO_EM_RESTART': '1', 03:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:45 INFO - 'PWD': '/builds/slave/test', 03:00:45 INFO - 'SHELL': '/bin/bash', 03:00:45 INFO - 'SHLVL': '1', 03:00:45 INFO - 'TERM': 'linux', 03:00:45 INFO - 'TMOUT': '86400', 03:00:45 INFO - 'USER': 'cltbld', 03:00:45 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:45 INFO - '_': '/tools/buildbot/bin/python'} 03:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:46 INFO - Downloading/unpacking psutil>=3.1.1 03:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:00:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:48 INFO - Installing collected packages: psutil 03:00:48 INFO - Running setup.py install for psutil 03:00:48 INFO - building 'psutil._psutil_linux' extension 03:00:48 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:00:48 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:00:48 INFO - building 'psutil._psutil_posix' extension 03:00:48 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:00:48 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:00:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:48 INFO - Successfully installed psutil 03:00:48 INFO - Cleaning up... 03:00:48 INFO - Return code: 0 03:00:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:00:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:00:48 INFO - Using env: {'DISPLAY': ':0', 03:00:48 INFO - 'HOME': '/home/cltbld', 03:00:48 INFO - 'LANG': 'en_US.UTF-8', 03:00:48 INFO - 'LANGUAGE': 'en_US:en', 03:00:48 INFO - 'LOGNAME': 'cltbld', 03:00:48 INFO - 'MAIL': '/var/mail/cltbld', 03:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:48 INFO - 'MOZ_NO_REMOTE': '1', 03:00:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:48 INFO - 'NO_EM_RESTART': '1', 03:00:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:48 INFO - 'PWD': '/builds/slave/test', 03:00:48 INFO - 'SHELL': '/bin/bash', 03:00:48 INFO - 'SHLVL': '1', 03:00:48 INFO - 'TERM': 'linux', 03:00:48 INFO - 'TMOUT': '86400', 03:00:48 INFO - 'USER': 'cltbld', 03:00:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:48 INFO - '_': '/tools/buildbot/bin/python'} 03:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:00:51 INFO - Installing collected packages: mozsystemmonitor 03:00:51 INFO - Running setup.py install for mozsystemmonitor 03:00:51 INFO - Successfully installed mozsystemmonitor 03:00:51 INFO - Cleaning up... 03:00:51 INFO - Return code: 0 03:00:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:00:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:00:51 INFO - Using env: {'DISPLAY': ':0', 03:00:51 INFO - 'HOME': '/home/cltbld', 03:00:51 INFO - 'LANG': 'en_US.UTF-8', 03:00:51 INFO - 'LANGUAGE': 'en_US:en', 03:00:51 INFO - 'LOGNAME': 'cltbld', 03:00:51 INFO - 'MAIL': '/var/mail/cltbld', 03:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:51 INFO - 'MOZ_NO_REMOTE': '1', 03:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:51 INFO - 'NO_EM_RESTART': '1', 03:00:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:51 INFO - 'PWD': '/builds/slave/test', 03:00:51 INFO - 'SHELL': '/bin/bash', 03:00:51 INFO - 'SHLVL': '1', 03:00:51 INFO - 'TERM': 'linux', 03:00:51 INFO - 'TMOUT': '86400', 03:00:51 INFO - 'USER': 'cltbld', 03:00:51 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:51 INFO - '_': '/tools/buildbot/bin/python'} 03:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:51 INFO - Downloading/unpacking jsonschema==2.5.1 03:00: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:00: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:00: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:00: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:00: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:00: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:00:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:00:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:00: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:00: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:00: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:00: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:00: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:00: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:00:53 INFO - Downloading functools32-3.2.3-2.tar.gz 03:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:00:53 INFO - warning: no files found matching '*.txt' 03:00:53 INFO - no previously-included directories found matching 'build' 03:00:53 INFO - no previously-included directories found matching 'dist' 03:00:53 INFO - no previously-included directories found matching '.git*' 03:00:53 INFO - Installing collected packages: jsonschema, functools32 03:00:53 INFO - Running setup.py install for functools32 03:00:53 INFO - warning: no files found matching '*.txt' 03:00:53 INFO - no previously-included directories found matching 'build' 03:00:53 INFO - no previously-included directories found matching 'dist' 03:00:53 INFO - no previously-included directories found matching '.git*' 03:00:53 INFO - Successfully installed jsonschema functools32 03:00:53 INFO - Cleaning up... 03:00:54 INFO - Return code: 0 03:00:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:00:54 INFO - Using env: {'DISPLAY': ':0', 03:00:54 INFO - 'HOME': '/home/cltbld', 03:00:54 INFO - 'LANG': 'en_US.UTF-8', 03:00:54 INFO - 'LANGUAGE': 'en_US:en', 03:00:54 INFO - 'LOGNAME': 'cltbld', 03:00:54 INFO - 'MAIL': '/var/mail/cltbld', 03:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:54 INFO - 'MOZ_NO_REMOTE': '1', 03:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:54 INFO - 'NO_EM_RESTART': '1', 03:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:54 INFO - 'PWD': '/builds/slave/test', 03:00:54 INFO - 'SHELL': '/bin/bash', 03:00:54 INFO - 'SHLVL': '1', 03:00:54 INFO - 'TERM': 'linux', 03:00:54 INFO - 'TMOUT': '86400', 03:00:54 INFO - 'USER': 'cltbld', 03:00:54 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:54 INFO - '_': '/tools/buildbot/bin/python'} 03:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:00:54 INFO - Cleaning up... 03:00:54 INFO - Return code: 0 03:00:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:00:54 INFO - Using env: {'DISPLAY': ':0', 03:00:54 INFO - 'HOME': '/home/cltbld', 03:00:54 INFO - 'LANG': 'en_US.UTF-8', 03:00:54 INFO - 'LANGUAGE': 'en_US:en', 03:00:54 INFO - 'LOGNAME': 'cltbld', 03:00:54 INFO - 'MAIL': '/var/mail/cltbld', 03:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:54 INFO - 'MOZ_NO_REMOTE': '1', 03:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:54 INFO - 'NO_EM_RESTART': '1', 03:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:54 INFO - 'PWD': '/builds/slave/test', 03:00:54 INFO - 'SHELL': '/bin/bash', 03:00:54 INFO - 'SHLVL': '1', 03:00:54 INFO - 'TERM': 'linux', 03:00:54 INFO - 'TMOUT': '86400', 03:00:54 INFO - 'USER': 'cltbld', 03:00:54 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:54 INFO - '_': '/tools/buildbot/bin/python'} 03:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:56 INFO - Downloading blobuploader-1.2.4.tar.gz 03:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:00:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:00: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:00: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:00: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:00: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:00: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:00: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:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:00:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:00: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:00: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:00: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:00: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:00: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:00: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:00:57 INFO - Downloading docopt-0.6.1.tar.gz 03:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:00:57 INFO - Installing collected packages: blobuploader, requests, docopt 03:00:57 INFO - Running setup.py install for blobuploader 03:00:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:00:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:00:57 INFO - Running setup.py install for requests 03:00:57 INFO - Running setup.py install for docopt 03:00:57 INFO - Successfully installed blobuploader requests docopt 03:00:57 INFO - Cleaning up... 03:00:57 INFO - Return code: 0 03:00:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:57 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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:00:57 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:00:57 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:00:57 INFO - Using env: {'DISPLAY': ':0', 03:00:57 INFO - 'HOME': '/home/cltbld', 03:00:57 INFO - 'LANG': 'en_US.UTF-8', 03:00:57 INFO - 'LANGUAGE': 'en_US:en', 03:00:57 INFO - 'LOGNAME': 'cltbld', 03:00:57 INFO - 'MAIL': '/var/mail/cltbld', 03:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:57 INFO - 'MOZ_NO_REMOTE': '1', 03:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:57 INFO - 'NO_EM_RESTART': '1', 03:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:57 INFO - 'PWD': '/builds/slave/test', 03:00:57 INFO - 'SHELL': '/bin/bash', 03:00:57 INFO - 'SHLVL': '1', 03:00:57 INFO - 'TERM': 'linux', 03:00:57 INFO - 'TMOUT': '86400', 03:00:57 INFO - 'USER': 'cltbld', 03:00:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:57 INFO - '_': '/tools/buildbot/bin/python'} 03:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:58 INFO - Running setup.py (path:/tmp/pip-l1oHXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:58 INFO - Running setup.py (path:/tmp/pip-TcyUgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:58 INFO - Running setup.py (path:/tmp/pip-biPd0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:58 INFO - Running setup.py (path:/tmp/pip-QnCDJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:58 INFO - Running setup.py (path:/tmp/pip-AJ8JuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:58 INFO - Running setup.py (path:/tmp/pip-A0nYPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:58 INFO - Running setup.py (path:/tmp/pip-XkEt0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:58 INFO - Running setup.py (path:/tmp/pip-W38GXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:58 INFO - Running setup.py (path:/tmp/pip-Z9qXFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:58 INFO - Running setup.py (path:/tmp/pip-GqLikd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:58 INFO - Running setup.py (path:/tmp/pip-xuDbU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:58 INFO - Running setup.py (path:/tmp/pip-CKH029-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:58 INFO - Running setup.py (path:/tmp/pip-qnXCe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:59 INFO - Running setup.py (path:/tmp/pip-Br3kE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:59 INFO - Running setup.py (path:/tmp/pip-XRJJJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:59 INFO - Running setup.py (path:/tmp/pip-0KT0AT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:59 INFO - Running setup.py (path:/tmp/pip-dTeyEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:00:59 INFO - Running setup.py install for manifestparser 03:00:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:00:59 INFO - Running setup.py install for mozcrash 03:00:59 INFO - Running setup.py install for mozdebug 03:00:59 INFO - Running setup.py install for mozdevice 03:00:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:00:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:00:59 INFO - Running setup.py install for mozfile 03:00:59 INFO - Running setup.py install for mozhttpd 03:00:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:00:59 INFO - Running setup.py install for mozinfo 03:01:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Running setup.py install for mozInstall 03:01:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Running setup.py install for mozleak 03:01:00 INFO - Running setup.py install for mozlog 03:01:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Running setup.py install for moznetwork 03:01:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Running setup.py install for mozprocess 03:01:00 INFO - Running setup.py install for mozprofile 03:01:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Running setup.py install for mozrunner 03:01:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:01:00 INFO - Running setup.py install for mozscreenshot 03:01:00 INFO - Running setup.py install for moztest 03:01:01 INFO - Running setup.py install for mozversion 03:01:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:01:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:01 INFO - Cleaning up... 03:01:01 INFO - Return code: 0 03:01:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:01 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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:01:01 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:01:01 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:01:01 INFO - Using env: {'DISPLAY': ':0', 03:01:01 INFO - 'HOME': '/home/cltbld', 03:01:01 INFO - 'LANG': 'en_US.UTF-8', 03:01:01 INFO - 'LANGUAGE': 'en_US:en', 03:01:01 INFO - 'LOGNAME': 'cltbld', 03:01:01 INFO - 'MAIL': '/var/mail/cltbld', 03:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:01 INFO - 'MOZ_NO_REMOTE': '1', 03:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:01 INFO - 'NO_EM_RESTART': '1', 03:01:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:01 INFO - 'PWD': '/builds/slave/test', 03:01:01 INFO - 'SHELL': '/bin/bash', 03:01:01 INFO - 'SHLVL': '1', 03:01:01 INFO - 'TERM': 'linux', 03:01:01 INFO - 'TMOUT': '86400', 03:01:01 INFO - 'USER': 'cltbld', 03:01:01 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:01 INFO - '_': '/tools/buildbot/bin/python'} 03:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:01 INFO - Running setup.py (path:/tmp/pip-Dxo3O5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:01 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:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:01 INFO - Running setup.py (path:/tmp/pip-eEvs6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:01 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:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:01 INFO - Running setup.py (path:/tmp/pip-e7sLwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:01 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:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:01 INFO - Running setup.py (path:/tmp/pip-p4fmGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:01 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:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:01 INFO - Running setup.py (path:/tmp/pip-D1YjJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:01 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:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:01 INFO - Running setup.py (path:/tmp/pip-MZ28sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:01 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:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:01 INFO - Running setup.py (path:/tmp/pip-Kk8D_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:01 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:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:01 INFO - Running setup.py (path:/tmp/pip-71TzuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:01 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:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:01 INFO - Running setup.py (path:/tmp/pip-JFQoBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:02 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:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:02 INFO - Running setup.py (path:/tmp/pip-PWNeXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:02 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:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:02 INFO - Running setup.py (path:/tmp/pip-tbrppk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:02 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:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:02 INFO - Running setup.py (path:/tmp/pip-sjoxbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:02 INFO - Running setup.py (path:/tmp/pip-2VCUyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:02 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:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:02 INFO - Running setup.py (path:/tmp/pip-A8E0VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:02 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:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:02 INFO - Running setup.py (path:/tmp/pip-O3DTOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:02 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:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:02 INFO - Running setup.py (path:/tmp/pip-Ny6h3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:02 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:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:02 INFO - Running setup.py (path:/tmp/pip-c15syt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:02 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:01:02 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:01:02 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:01:02 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:01:02 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:01:02 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:01:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:04 INFO - Downloading blessings-1.6.tar.gz 03:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:04 INFO - Installing collected packages: blessings 03:01:04 INFO - Running setup.py install for blessings 03:01:04 INFO - Successfully installed blessings 03:01:04 INFO - Cleaning up... 03:01:04 INFO - Return code: 0 03:01:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:01:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:05 INFO - Reading from file tmpfile_stdout 03:01:05 INFO - Current package versions: 03:01:05 INFO - argparse == 1.2.1 03:01:05 INFO - blessings == 1.6 03:01:05 INFO - blobuploader == 1.2.4 03:01:05 INFO - docopt == 0.6.1 03:01:05 INFO - functools32 == 3.2.3-2 03:01:05 INFO - jsonschema == 2.5.1 03:01:05 INFO - manifestparser == 1.1 03:01:05 INFO - mozInstall == 1.12 03:01:05 INFO - mozcrash == 1.0 03:01:05 INFO - mozdebug == 0.1 03:01:05 INFO - mozdevice == 0.49 03:01:05 INFO - mozfile == 1.2 03:01:05 INFO - mozhttpd == 0.7 03:01:05 INFO - mozinfo == 0.9 03:01:05 INFO - mozleak == 0.1 03:01:05 INFO - mozlog == 3.4 03:01:05 INFO - moznetwork == 0.27 03:01:05 INFO - mozprocess == 0.25 03:01:05 INFO - mozprofile == 0.28 03:01:05 INFO - mozrunner == 6.13 03:01:05 INFO - mozscreenshot == 0.1 03:01:05 INFO - mozsystemmonitor == 0.3 03:01:05 INFO - moztest == 0.8 03:01:05 INFO - mozversion == 1.4 03:01:05 INFO - psutil == 3.1.1 03:01:05 INFO - requests == 1.2.3 03:01:05 INFO - wsgiref == 0.1.2 03:01:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:05 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:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:05 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:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:05 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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:01:05 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:01:05 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:01:05 INFO - Using env: {'DISPLAY': ':0', 03:01:05 INFO - 'HOME': '/home/cltbld', 03:01:05 INFO - 'LANG': 'en_US.UTF-8', 03:01:05 INFO - 'LANGUAGE': 'en_US:en', 03:01:05 INFO - 'LOGNAME': 'cltbld', 03:01:05 INFO - 'MAIL': '/var/mail/cltbld', 03:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:05 INFO - 'MOZ_NO_REMOTE': '1', 03:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:05 INFO - 'NO_EM_RESTART': '1', 03:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:05 INFO - 'PWD': '/builds/slave/test', 03:01:05 INFO - 'SHELL': '/bin/bash', 03:01:05 INFO - 'SHLVL': '1', 03:01:05 INFO - 'TERM': 'linux', 03:01:05 INFO - 'TMOUT': '86400', 03:01:05 INFO - 'USER': 'cltbld', 03:01:05 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:05 INFO - '_': '/tools/buildbot/bin/python'} 03:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:05 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:01:05 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:01:05 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:01:05 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:01:05 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:01:05 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:01:05 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:01:05 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:01:05 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:01:05 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:01:05 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:01:05 INFO - Cleaning up... 03:01:05 INFO - Return code: 0 03:01:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:01:05 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:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:05 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:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:05 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 0x12f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', '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:01:05 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:01:05 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:01:05 INFO - Using env: {'DISPLAY': ':0', 03:01:05 INFO - 'HOME': '/home/cltbld', 03:01:05 INFO - 'LANG': 'en_US.UTF-8', 03:01:05 INFO - 'LANGUAGE': 'en_US:en', 03:01:05 INFO - 'LOGNAME': 'cltbld', 03:01:05 INFO - 'MAIL': '/var/mail/cltbld', 03:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:05 INFO - 'MOZ_NO_REMOTE': '1', 03:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:05 INFO - 'NO_EM_RESTART': '1', 03:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:05 INFO - 'PWD': '/builds/slave/test', 03:01:05 INFO - 'SHELL': '/bin/bash', 03:01:05 INFO - 'SHLVL': '1', 03:01:05 INFO - 'TERM': 'linux', 03:01:05 INFO - 'TMOUT': '86400', 03:01:05 INFO - 'USER': 'cltbld', 03:01:05 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:05 INFO - '_': '/tools/buildbot/bin/python'} 03:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:01:05 INFO - Cleaning up... 03:01:05 INFO - Return code: 0 03:01:05 INFO - Running post-action listener: _resource_record_post_action 03:01:05 INFO - Running post-action listener: _start_resource_monitoring 03:01:05 INFO - Starting resource monitoring. 03:01:05 INFO - [mozharness: 2017-04-06 10:01:05.598273Z] Finished create-virtualenv step (success) 03:01:05 INFO - [mozharness: 2017-04-06 10:01:05.598743Z] Running install step. 03:01:05 INFO - Running pre-action listener: _resource_record_pre_action 03:01:05 INFO - Running main action method: install 03:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:05 INFO - Reading from file tmpfile_stdout 03:01:05 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:01:05 INFO - Reading from file tmpfile_stdout 03:01:05 INFO - Output received: 03:01:05 INFO - Usage: mozinstall [options] installer 03:01:05 INFO - Options: 03:01:05 INFO - -h, --help show this help message and exit 03:01:05 INFO - -d DEST, --destination=DEST 03:01:05 INFO - Directory to install application into. [default: 03:01:05 INFO - "/builds/slave/test"] 03:01:05 INFO - --app=APP Application being installed. [default: firefox] 03:01:05 INFO - mkdir: /builds/slave/test/build/application 03:01:05 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:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:01:22 INFO - Reading from file tmpfile_stdout 03:01:22 INFO - Output received: 03:01:22 INFO - /builds/slave/test/build/application/firefox/firefox 03:01:22 INFO - Running post-action listener: _resource_record_post_action 03:01:22 INFO - [mozharness: 2017-04-06 10:01:22.456532Z] Finished install step (success) 03:01:22 INFO - [mozharness: 2017-04-06 10:01:22.456656Z] Running run-tests step. 03:01:22 INFO - Running pre-action listener: _resource_record_pre_action 03:01:22 INFO - Running main action method: run_tests 03:01:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:01:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:01:22 INFO - Python 2.7.3 03:01:22 INFO - Return code: 0 03:01:22 INFO - grabbing minidump binary from tooltool 03:01:22 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:01:22 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 0x158d290>, '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 0x158db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158e040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:01:22 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:01:22 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:01:22 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:01:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:01:22 INFO - Return code: 0 03:01:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:01:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:01:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:01:22 INFO - ENV: RUST_BACKTRACE is now 1 03:01:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:01:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:01:22 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/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:01:22 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/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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:01:22 INFO - Using env: {'DISPLAY': ':0', 03:01:22 INFO - 'HOME': '/home/cltbld', 03:01:22 INFO - 'LANG': 'en_US.UTF-8', 03:01:22 INFO - 'LANGUAGE': 'en_US:en', 03:01:22 INFO - 'LOGNAME': 'cltbld', 03:01:22 INFO - 'MAIL': '/var/mail/cltbld', 03:01:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:01:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:01:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:22 INFO - 'MOZ_NO_REMOTE': '1', 03:01:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:01:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:22 INFO - 'NO_EM_RESTART': '1', 03:01:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:22 INFO - 'PWD': '/builds/slave/test', 03:01:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:01:22 INFO - 'RUST_BACKTRACE': '1', 03:01:22 INFO - 'SHELL': '/bin/bash', 03:01:22 INFO - 'SHLVL': '1', 03:01:22 INFO - 'TERM': 'linux', 03:01:22 INFO - 'TMOUT': '86400', 03:01:22 INFO - 'USER': 'cltbld', 03:01:22 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940', 03:01:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:22 INFO - '_': '/tools/buildbot/bin/python'} 03:01:22 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/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:01:22 INFO - mozversion application_buildid: 20170406090923 03:01:22 INFO - mozversion application_changeset: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 03:01:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:01:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:01:22 INFO - mozversion application_name: Firefox 03:01:22 INFO - mozversion application_remotingname: firefox-dev 03:01:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:22 INFO - mozversion application_vendor: Mozilla 03:01:22 INFO - mozversion application_version: 54.0a2 03:01:22 INFO - mozversion platform_buildid: 20170406090923 03:01:22 INFO - mozversion platform_changeset: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 03:01:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:22 INFO - mozversion platform_version: 54.0a2 03:01:22 INFO - using testdate: 1491472882 03:01:22 INFO - actual date: 1491472882 03:01:22 INFO - starting webserver on 'localhost:39137' 03:01:22 INFO - SUITE-START | Running 2 tests 03:01:22 INFO - TEST-START | dromaeo_css 03:01:22 INFO - operating with platform_type : linux_ 03:01:22 INFO - Initialising browser for dromaeo_css test... 03:01:22 INFO - TEST-INFO | started process 30740 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyYJw2W/profile http://localhost:39137/getInfo.html) 03:01:26 INFO - PROCESS | 30740 | __metrics Screen width/height:1600/1200 03:01:26 INFO - PROCESS | 30740 | colorDepth:24 03:01:26 INFO - PROCESS | 30740 | Browser inner width/height: 1024/697 03:01:26 INFO - PROCESS | 30740 | __metrics 03:01:27 INFO - PROCESS | 30740 | 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:01:27 INFO - PROCESS | 30740 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:01:28 INFO - TEST-INFO | 30740: exit 0 03:01:28 INFO - Browser initialized. 03:01:28 INFO - Running cycle 1/1 for dromaeo_css test... 03:01:28 INFO - TEST-INFO | started process 30830 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyYJw2W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:40 INFO - PROCESS | 30830 | RSS: Main: 139579392 03:01:40 INFO - PROCESS | 30830 | 03:04:41 INFO - PROCESS | 30830 | Cycle 1(1): loaded http://localhost:39137/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39137/tests/dromaeo/cssquery-ext.html) 03:04:43 INFO - PROCESS | 30830 | RSS: Main: 282497024 03:04:43 INFO - PROCESS | 30830 | 03:06:29 INFO - PROCESS | 30830 | ************************* 03:06:29 INFO - PROCESS | 30830 | A coding exception was thrown and uncaught in a Task. 03:06:29 INFO - PROCESS | 30830 | 03:06:29 ERROR - PROCESS | 30830 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:06:29 INFO - PROCESS | 30830 | Full stack: 03:06:29 INFO - PROCESS | 30830 | ************************* 03:06:29 INFO - PROCESS | 30830 | ************************* 03:06:29 INFO - PROCESS | 30830 | A coding exception was thrown and uncaught in a Task. 03:06:29 INFO - PROCESS | 30830 | 03:06:29 ERROR - PROCESS | 30830 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:06:29 INFO - PROCESS | 30830 | Full stack: 03:06:29 INFO - PROCESS | 30830 | ************************* 03:06:29 INFO - PROCESS | 30830 | ************************* 03:06:29 INFO - PROCESS | 30830 | A coding exception was thrown and uncaught in a Task. 03:06:29 INFO - PROCESS | 30830 | 03:06:29 ERROR - PROCESS | 30830 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:06:29 INFO - PROCESS | 30830 | Full stack: 03:06:29 INFO - PROCESS | 30830 | ************************* 03:06:29 INFO - PROCESS | 30830 | ************************* 03:06:29 INFO - PROCESS | 30830 | A coding exception was thrown and uncaught in a Task. 03:06:29 INFO - PROCESS | 30830 | 03:06:29 ERROR - PROCESS | 30830 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:06:29 INFO - PROCESS | 30830 | Full stack: 03:06:29 INFO - PROCESS | 30830 | ************************* 03:07:45 INFO - PROCESS | 30830 | Cycle 1(1): loaded http://localhost:39137/tests/dromaeo/cssquery-ext.html (next: http://localhost:39137/tests/dromaeo/cssquery-jquery.html) 03:07:46 INFO - PROCESS | 30830 | RSS: Main: 220024832 03:07:46 INFO - PROCESS | 30830 | 03:10:48 INFO - PROCESS | 30830 | Cycle 1(1): loaded http://localhost:39137/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39137/tests/dromaeo/cssquery-mootools.html) 03:10:48 INFO - PROCESS | 30830 | RSS: Main: 204050432 03:10:48 INFO - PROCESS | 30830 | 03:13:50 INFO - PROCESS | 30830 | Cycle 1(1): loaded http://localhost:39137/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39137/tests/dromaeo/cssquery-prototype.html) 03:13:51 INFO - PROCESS | 30830 | RSS: Main: 220999680 03:13:51 INFO - PROCESS | 30830 | 03:16:54 INFO - PROCESS | 30830 | Cycle 1(1): loaded http://localhost:39137/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39137/tests/dromaeo/cssquery-yui.html) 03:16:54 INFO - PROCESS | 30830 | RSS: Main: 176623616 03:16:54 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | Cycle 1(1): loaded http://localhost:39137/tests/dromaeo/cssquery-yui.html (next: http://localhost:39137/tests/dromaeo/cssquery-dojo.html) 03:19:57 INFO - PROCESS | 30830 | RSS: Main: 262184960 03:19:57 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | __start_tp_report 03:19:57 INFO - PROCESS | 30830 | _x_x_mozilla_page_load 03:19:57 INFO - PROCESS | 30830 | _x_x_mozilla_page_load_details 03:19:57 INFO - PROCESS | 30830 | |i|pagename|runs| 03:19:57 INFO - PROCESS | 30830 | |0;dojo.html;3344.782589131706;3365.242820365417;3370.831458427078;3386.2380964283025;3408.522806807046;5648.096304591266;5716.11400232964;5732.168835518847;5750.281214848144;5780.161876527901;5744.281964754406;5805.15825205346;5812.1281807728865;5817.069268916975;5821.476067153955;5226.817061402851;5275.129603615399;5277.6305658603915;5291.761870715808;5314.335708036496;5210.2445145453885;5351.197320401932;5370.5435038021815;5371.75827087782;5390.973045134789;5248.317718696564;5262.0265250928505;5310.15037593985;5380.381256155533;5394.487523685263;5368.597355198356;5392.649477783932;5392.676439413609;5394.946050539523;5404.162354835026;5303.787848486056;5313.946860531422;5327.840164795062;5356.491133342326;5381.434949330303;5581.553475721973;5606.887862242773;5676.035074037382;5739.856503587402;5755.884882302373;6217.68911554425;6246.593971391845;6246.656433896134;6252.531060170505;6252.624842509418;6239.37606239377;6264.749410023639;6270.216222972129;6276.623402595813;6297.968510157466;5660.235868157787;5681.204631351614;5788.9710551447815;5827.067669172912;5844.035734103868;6385.297617262097;6414.358564143548;6425.967870160713;6429.164208652845;6432.421082102634;6090.512758979271;6148.907766383508;6168.444839964424;6170.136180934673;6174.289956654952;6034.24571928509;6073.635581865102;6119.329413111953;6250;6257.1240026395435;6200.844978875564;6200.937990620036;6214.689265536705;6217.316095229521;6225.906611400833;6034.336223015464;6068.059450785136;6068.817935461943;6255.40573645503;6257.9687101564205;4640.1879671058105;4676.0647870426055;4702.858914232578;4711.222648263067;4718.693284936468;4165.645920096765;4182;4237.237297286518;4251.851185208503;4271.700980931305;5670.06443936754;5947.732352044169;6060.545459090497;6090.908636370458;6093.786717464956;6240.812775616739;6282.151909492251;6286.151369565142;6317.368263173646;6321.841953951188;5857.062869940789;6041.214642096499;6053.213082299273;6061.848453788691;6068.848278793065;5273.6044796639635;5275.103232450416;5282.894342113136;5374.086405311106;5375.623706340519;6551.082848401227;6596.8350791230605;6597.9670101650145;6604.702788374535;6608.537402381883;6253.155823963707;6315.052742088724;6319.146915166394;6321.8735625288155;6339.270983836917;6324.367563243639;6363.681815909279;6424.38968298011;6449.4840412765925;6593.0769692244085;6147.170132032118;6148.16999705052;6155.322914479314;6162.506999440124;6167.876642467215;6299.08663243818;6307.526935479766;6307.873842523216;6309;6316.242050953915;5886.911696324644;5985.970070149621;6016.157737916783;6064.151018857276;6067;6128.717018009172;6130.877382452415;6144.126041557368;6148.569600127948;6152.907706384497;6116.174316467398;6117.174181485442;6124.38756124384;6128.233970753656;6132.509399248139;5910.113482977587;5973.880522389441;5975.790847320323;5988.8802223956145;6046.365131661112;5989.131575921554;5998.520118390431;6003.549733770072;6018.3379828217985;6033.366496517802 03:19:57 INFO - PROCESS | 30830 | |1;ext.html;767.3822572828874;837.4975014991005;851.8381507513574;912.5665308978232;918.8437965545857;30782.230444238878;31188.53217201741;34067.31865362691;42563.14873702524;43268;44572.554274457245;44820.775896120555;44868.551314486846;44960;45023.32465013027;8865.33509986752;8960.103989601053;8982.19160275575;8992.46045237288;8997.370184087116;7680.961595192016;7827.452078354518;10606.522706478188;10781.137508999262;10803.891961080368;10950.780984380308;10968.122550196005;10983.61557345493;10987.066099381562;10992.615258465956;10892.183086268562;10894.638170228722;10900.454977251105;10907.83638245427;10937.4531273436;10610.575576976911;10666.839997400046;10683.572657093708;10695.679129626124;10702.625408110756;40948.79525602383;41156.38265426021;41217.58782412168;41310.38034429486;41312.79343603293;4157.854475093387;4159.188958153161;4212.241796476633;4264;4286.335617979218;30108.24729381761;30139.246518836986;30172.69827301721;30195.245118871986;30697.38605227883;4328.156009578133;4425.114977004612;4431.778411079433;4472.127935052666;4515.119551687422;238811;239092;239520;241479.79260103762;241480;54577.45422545803;54622.726886365315;54670.17994730082;54682.179767302725;54703.72648136734;56551;57055.14417283744;57059.429405705414;57112.42887571071;57150;15875.920620397055;16175.595610109605;16225.026498409898;16241.51275461738;16268.267927943034;12077.335746534025;14168.858311416754;14173.574792756233;14177.858221417653;14178.432862685377;12466.439010244561;13755.724885502234;14022.22877741734;14382.928085359716;14543.27283635814;5561.805336813192;5570.053090974544;5668.234788303526;5670.659760414388;5735.304406928737;1019.7705516258783;1023.3603997501561;1025.1337619711333;1026.2559644258004;1026.7741096958657;28804.711952880203;28809.855950720113;28904;28969.420611588073;29206.56190157107;1069.5454431866426;1082.626493859617;1091.8034753744403;1104.1884215101893;1127.5546159267208;14600.34298456572;15260.542183734506;15460.690786184214;15463.768043479356;15710.371585136696;300243;301696;302525;302940;303025;48393.758031210324;48999;55613.721931390086;56444;63286.367136328045;1506.4652048522835;1525.2450037231642;1529.6175956010998;1537.3235776258412;1541.485413257774;31183.376332473676;32427.35145297128;32854.83572582122;32915.670843291264;33115.83442082774;45545.089098218516;45729.54270457386;45845.54154458546;45909;45915.54084459112;305788;305839;306864;306885;307940;30516.542251866686;30819.845900770353;31069.378612427172;37758.81120594379;37785.433218502294;33545;37523.43714844225;38687.41968870413;40428.59571404366;44838.10323793571;32041.839790800896;32136.678633214306;32330.83834580812;32503.674963250065;32504.83747581247;32019;32035.359292813548;32539.674603253665;32817.179570510925;33243.83378083191;37026.25947481089;37555.436668449445;37972.240555189295;38031.619683802805;38060;30631.2342191447;30713.846430767702;30953.380932380776;31058.223544411572;31236;24919;24944.50110997806;25000;25043.87478062598;25231.11691090803 03:19:57 INFO - PROCESS | 30830 | |2;jquery.html;465.49958794296117;469.3124572501285;473.75128057769643;484.13340121183086;487.68300604607003;19195.904020479895;20629.07169177387;20720;20820.47948801279;20912.477188070287;21042.684359734616;21278;22411.55176896461;22567.435814104654;22568.210112646062;6221.066839974006;6287.591306565058;6358.459530939876;6415.711292991803;6525.347465253484;6595.868082638344;6700.597964122144;6711.731530738764;6713.093732346144;7246.23914488979;7674.769756907302;7688.269614386624;7702.46082774206;7702.46082774206;7715.768526944201;7321.743738969137;7586.317231449196;7608.695652173907;7618.809529761745;7683.884741728879;7288.344049035559;7412.406332638455;7466.775996720107;7520.398368130536;7536.095668519758;24086.87956560206;24294;24537.754622453725;24733.134340298184;24821.751782482126;3133.9529907051415;3215.5980502437196;3245.2211469247445;3304.306095719902;3320.518524813913;15456.304466299043;16531.83468165327;16612.58468538284;16776.07731574763;16777.496675099643;2257.8645281283175;2280.5096904165516;2372.727136379321;2387.1286980252085;2405.482821193452;114167;116182.41908790403;116398.41800791027;117732.41133794362;121938;17931.641367172582;17940.55148621295;18192.181351839197;18396.540086497946;18793.906030470032;31984.68015319817;32110.197245068593;32145.839270803965;32285.417166626612;32401.35197296088;10359.22305827066;10369.118624916966;10429.426381549089;10448.73878153037;10554.788904221872;8743.956280218685;8788.42875213109;8863.202311791842;8997.865032024525;9017.954910225408;8895.332850036273;9400.106989836091;9467.195288400426;9499.857502137473;9510.239180865514;3823.101571130838;3851.1912498375095;3857.691384689218;3968.146848427602;4076.592340765959;953.1374106433689;968.9806203875883;974.4104816586005;975.8731364922514;1002.0680766886719;19979.700304495444;20281;20523.384298471068;20604.79395206029;20837.37487875366;1104.4146602300632;1106.623747925709;1126.622581435212;1148.0987424871437;1152.6311580294225;11012.614558490415;11178.27341222818;11260.887391126147;11329.150313726503;11381.886181138249;697131;699575;703180;706544;726784;97727.51136244416;102308.48845755724;106484.46757766316;124574.37712811377;125925.37037314755;1333.1534475607864;1356.19625897558;1376.5250988408982;1389.388668985643;1390.603677951779;18078.728819067986;18528.814711853247;18617.441476755717;18641.81358186455;18754.906225468785;51831.740841295556;52902.20646690226;53192.4680753203;53647.85408583613;53740.19389709236;71756.64121679358;72751.63624181846;72806;73032;73397.63301183673;11230.887691122984;12127.696807579881;12638.684032899251;12713.36433178356;12902.612921612366;14383;14393.712125757216;14536.200508971686;14600.415983360546;14831.92584037073;11610.24533405326;11639.529421172036;11657.41712914334;11717.472713728073;11770.117241207116;11504.654860354203;11755.529778809094;11851.348175850411;11866.76266474683;11945.641630751092;12726.68183295425;12875.678108047;12912.8063079052;12925.353732313535;12936.611901642966;11914.106442017057;11963.820542691692;12142.271463712206;12349.444275007467;12430;12239.08206884505;12436.564720234748;12440.564580239648;12651.87348126507;12732.299723515296 03:19:57 INFO - PROCESS | 30830 | |3;mootools.html;1449.2608769527537;1570.6151992761775;1598.2008995502247;1606.8071831380232;1609.2275707660315;9720.319577629569;10990.340579565222;11298.15263855211;11647.767044659124;11660.41697915103;11408.771824563524;11423.600173993911;11486.368249746261;11487.195896287243;11519.942400287986;2051.69224616308;2104.4738815296178;2387.2241521505493;2409.819263555232;2415.2754173747894;2351.400392899817;2404.5431368040017;2423.309356833303;2427.3324835670155;2428.6357046443086;2357.033616217351;2373.857568545884;2374.905003799846;2375.762423757628;2382.273406610983;2366.3610825077135;2374.157174203159;2375.2161786610377;2375.7624237576106;2381.416552944522;2160.902759375832;2184.541246338271;2186.8250539956766;2270.14869423966;2320.746796729764;11655.766884662344;11736.47185876629;11755.7061073473;11762.235454695418;11769.646910592695;5641.407652196502;5791.247137872049;5793.710314484259;5793.971030144822;5795.623284486495;3319.203391186122;3378.290558982592;3508.912277193065;3540.5574303212097;3561.4301711726;1328.740895525373;1329.4246318113067;1336.5589355512657;1343.5902049874785;1360.9863901361057;18428.999999999865;18480.722789158175;18551.257949681996;18565.44303670892;18586.349477768214;4690.33937429398;4720.787564559603;4809.134355815917;4819.614430845524;4881.877953051131;14609.92695036518;14611.342489587994;14624.634384140269;14627.488037918834;14640.487582934546;5850.707464626752;5956.255468066492;6015.879682406327;6056.394360563909;6063.787767428119;4480.887977800516;4610.308453731967;5319.946800532022;5366.597505187048;5521.199426083195;3940.802959851996;4043.4137050128156;4044.23159599681;4268.445102136702;4382;1164.598213616301;1174.6123779152858;1188.453311476713;1204.5783975608467;1210.2315029956042;962.956666949989;965.7489052846311;971.7279161834698;981.7496538382667;983.2920297385871;6828.2488926218075;6833.760818371333;6957.478189135835;7159;7170.139583250043;1081.0972837680624;1084.6766944327908;1090.1932569898377;1107.125370956935;1117.1509652664033;4433.334999749999;4445.755483448442;4447.866564003085;4513.830136659059;4515.81936722534;14824.407023719146;15010.47463338778;15168;15189.772153417707;15229.771553426708;14416.783748243784;15037.248137593077;15063.397464101532;15077.773833392508;15082.170480623452;10705.892941070646;10849.511771970123;10873.83689244662;10935.398553079656;10936.507857146447;7234.5659260444545;7442.330190282901;7496.62516874165;7580.1661817198965;7583.203763604742;13267.867321326663;13379.53171638988;13423.463061477432;13504.324783760969;13801;13762.311884405539;13780.931095344458;13800.102993305403;13827.654308642364;14132.50536231227;9864.950675246579;10076.546555404877;10333.74165645865;10350.068493835592;10352.171826254033;9327.766805829908;9580.760480987752;10981.505832237408;11383.943080284545;11457.484413201259;9304.674336398193;9337.579808908751;9387.202087822612;9481.952590237004;9684.903150968401;8815.250703690259;8864.689735859214;8901.643934242558;8956.865647015169;9042.81914361696;8154.836903261783;8162.877556836534;8201.71294004707;8210.548419836727;8218.424710270463;5922.496587790086;6239.688015599111;6311.400416960381;6386.93613063882;6388.201474815648;6050.42520960508;6066.908996365147;6180.6600636965395;6230.096635987666;6261.425959780557 03:19:57 INFO - PROCESS | 30830 | |4;prototype.html;401.4901075633943;413.87997480730587;426.78873957391096;433.6054190686476;437.6914275435817;5049.697018178903;5145.176771716526;5192.221166824978;5240.318758561387;5242.090639694968;5041.596672266219;5133.563647089993;5136.974315128419;5151.69089854609;5157.329547158864;4475.239209334405;4507.57178068083;4517.683762136652;4520.97739511302;4549.294859296814;4438.7780610969485;4452.265376212938;4462.419885414892;4545.318202269653;4551.772411379435;4496.190685676577;4501.617362524173;4505.346724724913;4515.480719717241;4532.161550113239;4497.9775101124615;4553.33976573395;4556.54434556545;4569.04050149469;4615.376924115269;4134.263609284108;4340.761258130801;4377.5403582623685;4392.099619578011;4396.582324679151;5011.799528018875;5041.571466425322;5056.089903817311;5070.188769796851;5113.414841399178;5079.4412614612365;5124.718820294926;5131.461196574381;5146.871328216787;5199.376074871002;5287.189152555812;5458.563314934835;5498.927709096728;5508.862278443031;5510.338759348863;4817.831375901862;4835.927461088087;4837.250226214942;4844.570851598787;4846.975765121187;5763;5773.278340207474;5775.797847075332;5791.681457519835;5810.7385167667135;4831.444383895827;4933.210686290176;4991.750412479361;5000.324956130876;5035.647504674637;4996.825111121093;5009;5014.6991180529285;5046.26829109777;5074;5133.845984620468;5209;5230.790768369223;5242.318498595235;5282.286891269706;5186.688798672092;5410.215518749759;5440.06877558461;5463.590230732711;5526.50261476469;1504.4884739188728;1535.4395645589502;1586;1619.336072210388;1624.382734560883;1221.1879100398314;1255.7614053329837;1267.8605353411117;1276.117726353621;1281.1288323939625;5337.546308563816;5340.385855626652;5349.839504814861;5373.919391209135;5420.512153906167;5561.249231353715;5582.944170558324;5605.785714642693;5613.775448981995;5636.549076073986;5260.71066091361;5304.787808487695;5308.654937429054;5358.5981051421295;5396.541293990055;2921.7516511096796;2940;2946.3665311958034;2953.7889465319117;2954.3500429905384;5719.025277432228;5790.739416726257;5820.650760954271;5853.414658534198;5884.6469211847425;5577.804776832872;5614;5668.659880407189;5711.828645140569;5768.711564421846;5785.4793068623185;5787.450192231731;5799.797007104731;5802;5819.854503637273;5214.348206474191;5550.195221692751;5576.191452239484;5581.10702287632;5599.748011339581;5522.66863988162;5528.7235638218735;5538.778448862;5542.58664040667;5559.11054231321;5755.71221438918;5824.9806283901;5836.824895253149;5874.471297583238;5903.2325797646035;4442.800073996613;4699.318598803223;4699.718016918996;4719.126961512131;4725.881852953704;5373.919391209213;5490.725463726878;5662.631928924607;5673.347565030074;5675.971620141873;5356.544693701079;5434.809781657781;5465.321992223702;5485.396606373375;5499.615026948075;5420.186971954238;5449.6185267032515;5452.672839629635;5470.206820011155;5506.834794956158;5245.580353571629;5379.2469054333205;5425.864353391197;5463.344398672185;5490.835274941758;5428.67427954324;5456.90861827644;5499.212755854283;5501.587380946365;5513.807016754395;5239.12304894383;5333.173358129496;5358.4105748366865;5415.564875308125;5425.213344065168 03:19:57 INFO - PROCESS | 30830 | |5;yui.html;802.5545822068749;829.7137487566791;832.6128350317105;835.2941764209243;887.4187407248253;16884.577885552866;18433.539161520952;18445.077746112707;18490.44528664142;18597.53506162345;15502.224888755574;16057.759133613004;16601.335946562154;16880.915595422004;17112.144392780374;849.821537477129;869.3088994249555;894.4275663575316;898.8696638987343;910.6084383715;912.885889263842;914.4970266353512;915.4598786715836;916.6058594804232;917.0966597901062;943.580106852451;943.8631398447221;943.8820147481565;944.3483996042709;944.3955868244329;938.6573900526292;940.1068984464717;940.6096469964956;941.4304345870787;942.6983365323099;694.836713372357;695.0165515795144;697.0206859362553;697.1982220446477;698.1099098649212;16182.109983950879;16377.918110409491;16447.753283700753;16495.175241237892;16537.255823487973;17135.91432042844;17359.524440422578;17585.472435826945;17644.382446614436;18228.36200732981;10934.343939363664;11244.325340479598;11269.887301126966;11391.487383067783;11669.474873630706;4066.79666016698;4092.631663150331;4100.36444351126;4116.567760385146;4136.31750761124;53220.73389633066;54415;61939;62241.68879155575;91539;8620.525871077025;11106.777864442827;12947.287899165449;12999.610011699664;13132.146410483288;24298.392540186283;24513.63229551558;24584.631230531555;24663.630045549333;24687.135950241656;8371.623276952552;9194.862077068849;9320.30097742672;9801.607935682636;9927.15619172364;5055.34280543527;5120.820771273062;5130.333056702605;5156.61325400589;5275.076861549213;3609.187932715118;3679.632036796299;3699.038250055005;3700.7224458165747;3705.481232627434;649.6069877724003;654.4110300729382;655.0370954696589;667.9365460281266;668.3249917583275;805.7945223967968;817.5748610722391;835.2983493865061;845.9788505287418;848.5587494502824;11209.551617935354;11336.263142895685;11462.369569673749;11469.093416890308;11779.587714429954;772.1930582541257;773.4972268025829;781.3436713160972;787.7597332813547;788.7239466186903;5501.284832971688;5528.198411230349;5673.461021203062;5687.658740475585;5693.28833895763;84780.57609712036;85710.57144714237;86433;86586;89697.55151224333;74381.25618743851;74493.25506744972;76269;76693.61653191698;78306.21693783104;67734;68371;68590;69659;70548.64725676441;10464.738381540523;10564.524596393027;10823.512941917475;10971.615993440191;10990.17573681961;44665.33002004908;45167.77416112898;45183.32225016561;46111;46224.30663539982;54493.45506545043;54784.17823732567;55453.7227313877;55553.444465556444;55970;12645;12751.426185821474;12965.935170324088;13166.275854828076;13205.735885282049;16495.505134845975;16707.66584668324;16782.916085419496;16852;18725.438236852915;16104.194790260675;16536.917315413826;16617.50147495577;17087.487375378758;17393.478195654152;15284.847151528342;15673.216339183224;15739.291731872323;15888.682226355177;16079;15957.920210398874;15990.760138597698;16100.516984490485;16587;16786.748198777274;11244.943775281072;11490.195686301879;11536.423178841193;11780.234284771463;12038.75922481528;12289;12305.753884922431;12331.198472099644;12556.121071524905;12562.623121306375 03:19:57 INFO - PROCESS | 30830 | __end_tp_report 03:19:57 INFO - PROCESS | 30830 | __start_cc_report 03:19:57 INFO - PROCESS | 30830 | _x_x_mozilla_cycle_collect,1392 03:19:57 INFO - PROCESS | 30830 | __end_cc_report 03:19:57 INFO - PROCESS | 30830 | __startTimestamp1491473997675__endTimestamp 03:19:57 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | ------- Summary: start ------- 03:19:57 INFO - PROCESS | 30830 | Number of tests: 6 03:19:57 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | [#0] dojo.html Cycles:165 Average:5791.86 Median:6041.21 stddev:669.58 (11.1%) stddev-sans-first:643.52 03:19:57 INFO - PROCESS | 30830 | Values: 3344.8 3365.2 3370.8 3386.2 3408.5 5648.1 5716.1 5732.2 5750.3 5780.2 5744.3 5805.2 5812.1 5817.1 5821.5 5226.8 5275.1 5277.6 5291.8 5314.3 5210.2 5351.2 5370.5 5371.8 5391.0 5248.3 5262.0 5310.2 5380.4 5394.5 5368.6 5392.6 5392.7 5394.9 5404.2 5303.8 5313.9 5327.8 5356.5 5381.4 5581.6 5606.9 5676.0 5739.9 5755.9 6217.7 6246.6 6246.7 6252.5 6252.6 6239.4 6264.7 6270.2 6276.6 6298.0 5660.2 5681.2 5789.0 5827.1 5844.0 6385.3 6414.4 6426.0 6429.2 6432.4 6090.5 6148.9 6168.4 6170.1 6174.3 6034.2 6073.6 6119.3 6250.0 6257.1 6200.8 6200.9 6214.7 6217.3 6225.9 6034.3 6068.1 6068.8 6255.4 6258.0 4640.2 4676.1 4702.9 4711.2 4718.7 4165.6 4182.0 4237.2 4251.9 4271.7 5670.1 5947.7 6060.5 6090.9 6093.8 6240.8 6282.2 6286.2 6317.4 6321.8 5857.1 6041.2 6053.2 6061.8 6068.8 5273.6 5275.1 5282.9 5374.1 5375.6 6551.1 6596.8 6598.0 6604.7 6608.5 6253.2 6315.1 6319.1 6321.9 6339.3 6324.4 6363.7 6424.4 6449.5 6593.1 6147.2 6148.2 6155.3 6162.5 6167.9 6299.1 6307.5 6307.9 6309.0 6316.2 5886.9 5986.0 6016.2 6064.2 6067.0 6128.7 6130.9 6144.1 6148.6 6152.9 6116.2 6117.2 6124.4 6128.2 6132.5 5910.1 5973.9 5975.8 5988.9 6046.4 5989.1 5998.5 6003.5 6018.3 6033.4 03:19:57 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | [#1] ext.html Cycles:180 Average:45414.24 Median:31126.38 stddev:74210.62 (238.4%) stddev-sans-first:74343.08 03:19:57 INFO - PROCESS | 30830 | Values: 767.4 837.5 851.8 912.6 918.8 30782.2 31188.5 34067.3 42563.1 43268.0 44572.6 44820.8 44868.6 44960.0 45023.3 8865.3 8960.1 8982.2 8992.5 8997.4 7681.0 7827.5 10606.5 10781.1 10803.9 10950.8 10968.1 10983.6 10987.1 10992.6 10892.2 10894.6 10900.5 10907.8 10937.5 10610.6 10666.8 10683.6 10695.7 10702.6 40948.8 41156.4 41217.6 41310.4 41312.8 4157.9 4159.2 4212.2 4264.0 4286.3 30108.2 30139.2 30172.7 30195.2 30697.4 4328.2 4425.1 4431.8 4472.1 4515.1 238811.0 239092.0 239520.0 241479.8 241480.0 54577.5 54622.7 54670.2 54682.2 54703.7 56551.0 57055.1 57059.4 57112.4 57150.0 15875.9 16175.6 16225.0 16241.5 16268.3 12077.3 14168.9 14173.6 14177.9 14178.4 12466.4 13755.7 14022.2 14382.9 14543.3 5561.8 5570.1 5668.2 5670.7 5735.3 1019.8 1023.4 1025.1 1026.3 1026.8 28804.7 28809.9 28904.0 28969.4 29206.6 1069.5 1082.6 1091.8 1104.2 1127.6 14600.3 15260.5 15460.7 15463.8 15710.4 300243.0 301696.0 302525.0 302940.0 303025.0 48393.8 48999.0 55613.7 56444.0 63286.4 1506.5 1525.2 1529.6 1537.3 1541.5 31183.4 32427.4 32854.8 32915.7 33115.8 45545.1 45729.5 45845.5 45909.0 45915.5 305788.0 305839.0 306864.0 306885.0 307940.0 30516.5 30819.8 31069.4 37758.8 37785.4 33545.0 37523.4 38687.4 40428.6 44838.1 32041.8 32136.7 32330.8 32503.7 32504.8 32019.0 32035.4 32539.7 32817.2 33243.8 37026.3 37555.4 37972.2 38031.6 38060.0 30631.2 30713.8 30953.4 31058.2 31236.0 24919.0 24944.5 25000.0 25043.9 25231.1 03:19:57 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | [#2] jquery.html Cycles:180 Average:39194.00 Median:21160.34 stddev:116307.10 (549.6%) stddev-sans-first:116597.02 03:19:57 INFO - PROCESS | 30830 | Values: 465.5 469.3 473.8 484.1 487.7 19195.9 20629.1 20720.0 20820.5 20912.5 21042.7 21278.0 22411.6 22567.4 22568.2 6221.1 6287.6 6358.5 6415.7 6525.3 6595.9 6700.6 6711.7 6713.1 7246.2 7674.8 7688.3 7702.5 7702.5 7715.8 7321.7 7586.3 7608.7 7618.8 7683.9 7288.3 7412.4 7466.8 7520.4 7536.1 24086.9 24294.0 24537.8 24733.1 24821.8 3134.0 3215.6 3245.2 3304.3 3320.5 15456.3 16531.8 16612.6 16776.1 16777.5 2257.9 2280.5 2372.7 2387.1 2405.5 114167.0 116182.4 116398.4 117732.4 121938.0 17931.6 17940.6 18192.2 18396.5 18793.9 31984.7 32110.2 32145.8 32285.4 32401.4 10359.2 10369.1 10429.4 10448.7 10554.8 8744.0 8788.4 8863.2 8997.9 9018.0 8895.3 9400.1 9467.2 9499.9 9510.2 3823.1 3851.2 3857.7 3968.1 4076.6 953.1 969.0 974.4 975.9 1002.1 19979.7 20281.0 20523.4 20604.8 20837.4 1104.4 1106.6 1126.6 1148.1 1152.6 11012.6 11178.3 11260.9 11329.2 11381.9 697131.0 699575.0 703180.0 706544.0 726784.0 97727.5 102308.5 106484.5 124574.4 125925.4 1333.2 1356.2 1376.5 1389.4 1390.6 18078.7 18528.8 18617.4 18641.8 18754.9 51831.7 52902.2 53192.5 53647.9 53740.2 71756.6 72751.6 72806.0 73032.0 73397.6 11230.9 12127.7 12638.7 12713.4 12902.6 14383.0 14393.7 14536.2 14600.4 14831.9 11610.2 11639.5 11657.4 11717.5 11770.1 11504.7 11755.5 11851.3 11866.8 11945.6 12726.7 12875.7 12912.8 12925.4 12936.6 11914.1 11963.8 12142.3 12349.4 12430.0 12239.1 12436.6 12440.6 12651.9 12732.3 03:19:57 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | [#3] mootools.html Cycles:180 Average:7257.21 Median:2375.76 stddev:4817.10 (202.8%) stddev-sans-first:4810.85 03:19:57 INFO - PROCESS | 30830 | Values: 1449.3 1570.6 1598.2 1606.8 1609.2 9720.3 10990.3 11298.2 11647.8 11660.4 11408.8 11423.6 11486.4 11487.2 11519.9 2051.7 2104.5 2387.2 2409.8 2415.3 2351.4 2404.5 2423.3 2427.3 2428.6 2357.0 2373.9 2374.9 2375.8 2382.3 2366.4 2374.2 2375.2 2375.8 2381.4 2160.9 2184.5 2186.8 2270.1 2320.7 11655.8 11736.5 11755.7 11762.2 11769.6 5641.4 5791.2 5793.7 5794.0 5795.6 3319.2 3378.3 3508.9 3540.6 3561.4 1328.7 1329.4 1336.6 1343.6 1361.0 18429.0 18480.7 18551.3 18565.4 18586.3 4690.3 4720.8 4809.1 4819.6 4881.9 14609.9 14611.3 14624.6 14627.5 14640.5 5850.7 5956.3 6015.9 6056.4 6063.8 4480.9 4610.3 5319.9 5366.6 5521.2 3940.8 4043.4 4044.2 4268.4 4382.0 1164.6 1174.6 1188.5 1204.6 1210.2 963.0 965.7 971.7 981.7 983.3 6828.2 6833.8 6957.5 7159.0 7170.1 1081.1 1084.7 1090.2 1107.1 1117.2 4433.3 4445.8 4447.9 4513.8 4515.8 14824.4 15010.5 15168.0 15189.8 15229.8 14416.8 15037.2 15063.4 15077.8 15082.2 10705.9 10849.5 10873.8 10935.4 10936.5 7234.6 7442.3 7496.6 7580.2 7583.2 13267.9 13379.5 13423.5 13504.3 13801.0 13762.3 13780.9 13800.1 13827.7 14132.5 9865.0 10076.5 10333.7 10350.1 10352.2 9327.8 9580.8 10981.5 11383.9 11457.5 9304.7 9337.6 9387.2 9482.0 9684.9 8815.3 8864.7 8901.6 8956.9 9042.8 8154.8 8162.9 8201.7 8210.5 8218.4 5922.5 6239.7 6311.4 6386.9 6388.2 6050.4 6066.9 6180.7 6230.1 6261.4 03:19:57 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | [#4] prototype.html Cycles:180 Average:4822.43 Median:5242.20 stddev:1266.08 (24.2%) stddev-sans-first:1225.37 03:19:57 INFO - PROCESS | 30830 | Values: 401.5 413.9 426.8 433.6 437.7 5049.7 5145.2 5192.2 5240.3 5242.1 5041.6 5133.6 5137.0 5151.7 5157.3 4475.2 4507.6 4517.7 4521.0 4549.3 4438.8 4452.3 4462.4 4545.3 4551.8 4496.2 4501.6 4505.3 4515.5 4532.2 4498.0 4553.3 4556.5 4569.0 4615.4 4134.3 4340.8 4377.5 4392.1 4396.6 5011.8 5041.6 5056.1 5070.2 5113.4 5079.4 5124.7 5131.5 5146.9 5199.4 5287.2 5458.6 5498.9 5508.9 5510.3 4817.8 4835.9 4837.3 4844.6 4847.0 5763.0 5773.3 5775.8 5791.7 5810.7 4831.4 4933.2 4991.8 5000.3 5035.6 4996.8 5009.0 5014.7 5046.3 5074.0 5133.8 5209.0 5230.8 5242.3 5282.3 5186.7 5410.2 5440.1 5463.6 5526.5 1504.5 1535.4 1586.0 1619.3 1624.4 1221.2 1255.8 1267.9 1276.1 1281.1 5337.5 5340.4 5349.8 5373.9 5420.5 5561.2 5582.9 5605.8 5613.8 5636.5 5260.7 5304.8 5308.7 5358.6 5396.5 2921.8 2940.0 2946.4 2953.8 2954.4 5719.0 5790.7 5820.7 5853.4 5884.6 5577.8 5614.0 5668.7 5711.8 5768.7 5785.5 5787.5 5799.8 5802.0 5819.9 5214.3 5550.2 5576.2 5581.1 5599.7 5522.7 5528.7 5538.8 5542.6 5559.1 5755.7 5825.0 5836.8 5874.5 5903.2 4442.8 4699.3 4699.7 4719.1 4725.9 5373.9 5490.7 5662.6 5673.3 5676.0 5356.5 5434.8 5465.3 5485.4 5499.6 5420.2 5449.6 5452.7 5470.2 5506.8 5245.6 5379.2 5425.9 5463.3 5490.8 5428.7 5456.9 5499.2 5501.6 5513.8 5239.1 5333.2 5358.4 5415.6 5425.2 03:19:57 INFO - PROCESS | 30830 | 03:19:57 INFO - PROCESS | 30830 | [#5] yui.html Cycles:180 Average:18713.33 Median:45175.55 stddev:23017.14 (51.0%) stddev-sans-first:23042.41 03:19:57 INFO - PROCESS | 30830 | Values: 802.6 829.7 832.6 835.3 887.4 16884.6 18433.5 18445.1 18490.4 18597.5 15502.2 16057.8 16601.3 16880.9 17112.1 849.8 869.3 894.4 898.9 910.6 912.9 914.5 915.5 916.6 917.1 943.6 943.9 943.9 944.3 944.4 938.7 940.1 940.6 941.4 942.7 694.8 695.0 697.0 697.2 698.1 16182.1 16377.9 16447.8 16495.2 16537.3 17135.9 17359.5 17585.5 17644.4 18228.4 10934.3 11244.3 11269.9 11391.5 11669.5 4066.8 4092.6 4100.4 4116.6 4136.3 53220.7 54415.0 61939.0 62241.7 91539.0 8620.5 11106.8 12947.3 12999.6 13132.1 24298.4 24513.6 24584.6 24663.6 24687.1 8371.6 9194.9 9320.3 9801.6 9927.2 5055.3 5120.8 5130.3 5156.6 5275.1 3609.2 3679.6 3699.0 3700.7 3705.5 649.6 654.4 655.0 667.9 668.3 805.8 817.6 835.3 846.0 848.6 11209.6 11336.3 11462.4 11469.1 11779.6 772.2 773.5 781.3 787.8 788.7 5501.3 5528.2 5673.5 5687.7 5693.3 84780.6 85710.6 86433.0 86586.0 89697.6 74381.3 74493.3 76269.0 76693.6 78306.2 67734.0 68371.0 68590.0 69659.0 70548.6 10464.7 10564.5 10823.5 10971.6 10990.2 44665.3 45167.8 45183.3 46111.0 46224.3 54493.5 54784.2 55453.7 55553.4 55970.0 12645.0 12751.4 12965.9 13166.3 13205.7 16495.5 16707.7 16782.9 16852.0 18725.4 16104.2 16536.9 16617.5 17087.5 17393.5 15284.8 15673.2 15739.3 15888.7 16079.0 15957.9 15990.8 16100.5 16587.0 16786.7 11244.9 11490.2 11536.4 11780.2 12038.8 12289.0 12305.8 12331.2 12556.1 12562.6 03:19:57 INFO - PROCESS | 30830 | -------- Summary: end -------- 03:19:57 INFO - PROCESS | 30830 | 03:19:58 INFO - TEST-INFO | 30830: exit 0 03:19:58 INFO - TEST-OK | dromaeo_css | took 1115791ms 03:19:58 INFO - TEST-START | kraken 03:19:58 INFO - operating with platform_type : linux_ 03:19:58 INFO - Initialising browser for kraken test... 03:19:58 INFO - TEST-INFO | started process 31157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeL4tnG/profile http://localhost:39137/getInfo.html) 03:20:02 INFO - PROCESS | 31157 | __metrics Screen width/height:1600/1200 03:20:02 INFO - PROCESS | 31157 | colorDepth:24 03:20:02 INFO - PROCESS | 31157 | Browser inner width/height: 1024/697 03:20:02 INFO - PROCESS | 31157 | __metrics 03:20:03 INFO - PROCESS | 31157 | 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:20:03 INFO - PROCESS | 31157 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:20:04 INFO - TEST-INFO | 31157: exit 0 03:20:04 INFO - Browser initialized. 03:20:04 INFO - Running cycle 1/1 for kraken test... 03:20:04 INFO - TEST-INFO | started process 31253 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeL4tnG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:16 INFO - PROCESS | 31253 | RSS: Main: 140070912 03:20:16 INFO - PROCESS | 31253 | 03:20:18 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39137/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:19 INFO - PROCESS | 31253 | RSS: Main: 183984128 03:20:19 INFO - PROCESS | 31253 | 03:20:21 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39137/tests/kraken/driver.html?testName=audio-dft) 03:20:22 INFO - PROCESS | 31253 | RSS: Main: 174161920 03:20:22 INFO - PROCESS | 31253 | 03:20:25 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39137/tests/kraken/driver.html?testName=audio-fft) 03:20:25 INFO - PROCESS | 31253 | RSS: Main: 265904128 03:20:25 INFO - PROCESS | 31253 | 03:20:28 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39137/tests/kraken/driver.html?testName=audio-oscillator) 03:20:28 INFO - PROCESS | 31253 | RSS: Main: 171544576 03:20:28 INFO - PROCESS | 31253 | 03:20:30 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39137/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:20:31 INFO - PROCESS | 31253 | RSS: Main: 174260224 03:20:31 INFO - PROCESS | 31253 | 03:20:34 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39137/tests/kraken/driver.html?testName=imaging-darkroom) 03:20:34 INFO - PROCESS | 31253 | RSS: Main: 213618688 03:20:34 INFO - PROCESS | 31253 | 03:20:38 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39137/tests/kraken/driver.html?testName=imaging-desaturate) 03:20:38 INFO - PROCESS | 31253 | RSS: Main: 212201472 03:20:38 INFO - PROCESS | 31253 | 03:20:41 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39137/tests/kraken/driver.html?testName=json-parse-financial) 03:20:42 INFO - PROCESS | 31253 | RSS: Main: 210407424 03:20:42 INFO - PROCESS | 31253 | 03:20:43 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39137/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:20:44 INFO - PROCESS | 31253 | RSS: Main: 172875776 03:20:44 INFO - PROCESS | 31253 | 03:20:45 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39137/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:20:46 INFO - PROCESS | 31253 | RSS: Main: 179572736 03:20:46 INFO - PROCESS | 31253 | 03:20:48 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39137/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:20:49 INFO - PROCESS | 31253 | RSS: Main: 189263872 03:20:49 INFO - PROCESS | 31253 | 03:20:51 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39137/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:20:52 INFO - PROCESS | 31253 | RSS: Main: 199118848 03:20:52 INFO - PROCESS | 31253 | 03:20:55 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39137/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:20:55 INFO - PROCESS | 31253 | RSS: Main: 195481600 03:20:55 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | Cycle 1(1): loaded http://localhost:39137/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39137/tests/kraken/driver.html?testName=ai-astar) 03:20:57 INFO - PROCESS | 31253 | RSS: Main: 244981760 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | __start_tp_report 03:20:57 INFO - PROCESS | 31253 | _x_x_mozilla_page_load 03:20:57 INFO - PROCESS | 31253 | _x_x_mozilla_page_load_details 03:20:57 INFO - PROCESS | 31253 | |i|pagename|runs| 03:20:57 INFO - PROCESS | 31253 | |0;ai-astar;104;112;112;113;113;113;111;113;112;112 03:20:57 INFO - PROCESS | 31253 | |1;audio-beat-detection;148;116;116;157;117;117;116;114;130;117 03:20:57 INFO - PROCESS | 31253 | |2;audio-dft;161;162;162;163;162;162;161;162;163;160 03:20:57 INFO - PROCESS | 31253 | |3;audio-fft;75;76;77;112;77;75;75;75;89;76 03:20:57 INFO - PROCESS | 31253 | |4;audio-oscillator;82;83;110;82;81;82;91;81;81;111 03:20:57 INFO - PROCESS | 31253 | |5;imaging-gaussian-blur;113;112;112;113;113;113;114;114;113;113 03:20:57 INFO - PROCESS | 31253 | |6;imaging-darkroom;147;146;146;146;147;145;145;146;146;145 03:20:57 INFO - PROCESS | 31253 | |7;imaging-desaturate;94;93;94;94;94;94;94;93;94;94 03:20:57 INFO - PROCESS | 31253 | |8;json-parse-financial;54;53;55;55;53;55;54;54;64;53 03:20:57 INFO - PROCESS | 31253 | |9;json-stringify-tinderbox;54;64;57;53;53;54;53;53;53;52 03:20:57 INFO - PROCESS | 31253 | |10;stanford-crypto-aes;76;76;76;79;79;80;82;86;80;74 03:20:57 INFO - PROCESS | 31253 | |11;stanford-crypto-ccm;125;121;123;123;123;127;119;121;163;119 03:20:57 INFO - PROCESS | 31253 | |12;stanford-crypto-pbkdf2;158;159;160;157;157;166;169;169;162;162 03:20:57 INFO - PROCESS | 31253 | |13;stanford-crypto-sha256-iterative;61;60;59;60;60;59;59;59;58;59 03:20:57 INFO - PROCESS | 31253 | __end_tp_report 03:20:57 INFO - PROCESS | 31253 | __start_cc_report 03:20:57 INFO - PROCESS | 31253 | _x_x_mozilla_cycle_collect,1680 03:20:57 INFO - PROCESS | 31253 | __end_cc_report 03:20:57 INFO - PROCESS | 31253 | __startTimestamp1491474057687__endTimestamp 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | ------- Summary: start ------- 03:20:57 INFO - PROCESS | 31253 | Number of tests: 14 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.50 stddev:2.72 (2.4%) stddev-sans-first:0.71 03:20:57 INFO - PROCESS | 31253 | Values: 104.0 112.0 112.0 113.0 113.0 113.0 111.0 113.0 112.0 112.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:117.00 stddev:15.40 (13.2%) stddev-sans-first:13.85 03:20:57 INFO - PROCESS | 31253 | Values: 148.0 116.0 116.0 157.0 117.0 117.0 116.0 114.0 130.0 117.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 03:20:57 INFO - PROCESS | 31253 | Values: 161.0 162.0 162.0 163.0 162.0 162.0 161.0 162.0 163.0 160.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#3] audio-fft Cycles:10 Average:80.70 Median:76.00 stddev:11.79 (15.5%) stddev-sans-first:12.32 03:20:57 INFO - PROCESS | 31253 | Values: 75.0 76.0 77.0 112.0 77.0 75.0 75.0 75.0 89.0 76.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:82.00 stddev:12.02 (14.7%) stddev-sans-first:12.52 03:20:57 INFO - PROCESS | 31253 | Values: 82.0 83.0 110.0 82.0 81.0 82.0 91.0 81.0 81.0 111.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 03:20:57 INFO - PROCESS | 31253 | Values: 113.0 112.0 112.0 113.0 113.0 113.0 114.0 114.0 113.0 113.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 03:20:57 INFO - PROCESS | 31253 | Values: 147.0 146.0 146.0 146.0 147.0 145.0 145.0 146.0 146.0 145.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:20:57 INFO - PROCESS | 31253 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:54.50 stddev:3.27 (6.0%) stddev-sans-first:3.44 03:20:57 INFO - PROCESS | 31253 | Values: 54.0 53.0 55.0 55.0 53.0 55.0 54.0 54.0 64.0 53.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#9] json-stringify-tinderbox Cycles:10 Average:54.60 Median:53.50 stddev:3.57 (6.7%) stddev-sans-first:3.77 03:20:57 INFO - PROCESS | 31253 | Values: 54.0 64.0 57.0 53.0 53.0 54.0 53.0 53.0 53.0 52.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.50 stddev:3.52 (4.4%) stddev-sans-first:3.59 03:20:57 INFO - PROCESS | 31253 | Values: 76.0 76.0 76.0 79.0 79.0 80.0 82.0 86.0 80.0 74.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:123.00 stddev:13.10 (10.7%) stddev-sans-first:13.88 03:20:57 INFO - PROCESS | 31253 | Values: 125.0 121.0 123.0 123.0 123.0 127.0 119.0 121.0 163.0 119.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.00 stddev:4.63 (2.9%) stddev-sans-first:4.69 03:20:57 INFO - PROCESS | 31253 | Values: 158.0 159.0 160.0 157.0 157.0 166.0 169.0 169.0 162.0 162.0 03:20:57 INFO - PROCESS | 31253 | 03:20:57 INFO - PROCESS | 31253 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.40 Median:59.50 stddev:0.84 (1.4%) stddev-sans-first:0.67 03:20:57 INFO - PROCESS | 31253 | Values: 61.0 60.0 59.0 60.0 60.0 59.0 59.0 59.0 58.0 59.0 03:20:57 INFO - PROCESS | 31253 | -------- Summary: end -------- 03:20:57 INFO - PROCESS | 31253 | 03:20:58 INFO - TEST-INFO | 31253: exit 0 03:20:58 INFO - TEST-OK | kraken | took 60239ms 03:20:58 INFO - SUITE-END | took 1176s 03:20:58 INFO - Completed test suite (00:19:36) 03:20:58 INFO - javascript benchmark 03:20:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7907.759154400314, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.782589131706, 3365.242820365417, 3370.831458427078, 3386.2380964283025, 3408.522806807046, 5648.096304591266, 5716.11400232964, 5732.168835518847, 5750.281214848144, 5780.161876527901, 5744.281964754406, 5805.15825205346, 5812.1281807728865, 5817.069268916975, 5821.476067153955, 5226.817061402851, 5275.129603615399, 5277.6305658603915, 5291.761870715808, 5314.335708036496, 5210.2445145453885, 5351.197320401932, 5370.5435038021815, 5371.75827087782, 5390.973045134789, 5248.317718696564, 5262.0265250928505, 5310.15037593985, 5380.381256155533, 5394.487523685263, 5368.597355198356, 5392.649477783932, 5392.676439413609, 5394.946050539523, 5404.162354835026, 5303.787848486056, 5313.946860531422, 5327.840164795062, 5356.491133342326, 5381.434949330303, 5581.553475721973, 5606.887862242773, 5676.035074037382, 5739.856503587402, 5755.884882302373, 6217.68911554425, 6246.593971391845, 6246.656433896134, 6252.531060170505, 6252.624842509418, 6239.37606239377, 6264.749410023639, 6270.216222972129, 6276.623402595813, 6297.968510157466, 5660.235868157787, 5681.204631351614, 5788.9710551447815, 5827.067669172912, 5844.035734103868, 6385.297617262097, 6414.358564143548, 6425.967870160713, 6429.164208652845, 6432.421082102634, 6090.512758979271, 6148.907766383508, 6168.444839964424, 6170.136180934673, 6174.289956654952, 6034.24571928509, 6073.635581865102, 6119.329413111953, 6250.0, 6257.1240026395435, 6200.844978875564, 6200.937990620036, 6214.689265536705, 6217.316095229521, 6225.906611400833, 6034.336223015464, 6068.059450785136, 6068.817935461943, 6255.40573645503, 6257.9687101564205, 4640.1879671058105, 4676.0647870426055, 4702.858914232578, 4711.222648263067, 4718.693284936468, 4165.645920096765, 4182.0, 4237.237297286518, 4251.851185208503, 4271.700980931305, 5670.06443936754, 5947.732352044169, 6060.545459090497, 6090.908636370458, 6093.786717464956, 6240.812775616739, 6282.151909492251, 6286.151369565142, 6317.368263173646, 6321.841953951188, 5857.062869940789, 6041.214642096499, 6053.213082299273, 6061.848453788691, 6068.848278793065, 5273.6044796639635, 5275.103232450416, 5282.894342113136, 5374.086405311106, 5375.623706340519, 6551.082848401227, 6596.8350791230605, 6597.9670101650145, 6604.702788374535, 6608.537402381883, 6253.155823963707, 6315.052742088724, 6319.146915166394, 6321.8735625288155, 6339.270983836917, 6324.367563243639, 6363.681815909279, 6424.38968298011, 6449.4840412765925, 6593.0769692244085, 6147.170132032118, 6148.16999705052, 6155.322914479314, 6162.506999440124, 6167.876642467215, 6299.08663243818, 6307.526935479766, 6307.873842523216, 6309.0, 6316.242050953915, 5886.911696324644, 5985.970070149621, 6016.157737916783, 6064.151018857276, 6067.0, 6128.717018009172, 6130.877382452415, 6144.126041557368, 6148.569600127948, 6152.907706384497, 6116.174316467398, 6117.174181485442, 6124.38756124384, 6128.233970753656, 6132.509399248139, 5910.113482977587, 5973.880522389441, 5975.790847320323, 5988.8802223956145, 6046.365131661112, 5989.131575921554, 5998.520118390431, 6003.549733770072, 6018.3379828217985, 6033.366496517802], "value": 5746.000231958345, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.3822572828874, 837.4975014991005, 851.8381507513574, 912.5665308978232, 918.8437965545857, 30782.230444238878, 31188.53217201741, 34067.31865362691, 42563.14873702524, 43268.0, 44572.554274457245, 44820.775896120555, 44868.551314486846, 44960.0, 45023.32465013027, 8865.33509986752, 8960.103989601053, 8982.19160275575, 8992.46045237288, 8997.370184087116, 7680.961595192016, 7827.452078354518, 10606.522706478188, 10781.137508999262, 10803.891961080368, 10950.780984380308, 10968.122550196005, 10983.61557345493, 10987.066099381562, 10992.615258465956, 10892.183086268562, 10894.638170228722, 10900.454977251105, 10907.83638245427, 10937.4531273436, 10610.575576976911, 10666.839997400046, 10683.572657093708, 10695.679129626124, 10702.625408110756, 40948.79525602383, 41156.38265426021, 41217.58782412168, 41310.38034429486, 41312.79343603293, 4157.854475093387, 4159.188958153161, 4212.241796476633, 4264.0, 4286.335617979218, 30108.24729381761, 30139.246518836986, 30172.69827301721, 30195.245118871986, 30697.38605227883, 4328.156009578133, 4425.114977004612, 4431.778411079433, 4472.127935052666, 4515.119551687422, 238811.0, 239092.0, 239520.0, 241479.79260103762, 241480.0, 54577.45422545803, 54622.726886365315, 54670.17994730082, 54682.179767302725, 54703.72648136734, 56551.0, 57055.14417283744, 57059.429405705414, 57112.42887571071, 57150.0, 15875.920620397055, 16175.595610109605, 16225.026498409898, 16241.51275461738, 16268.267927943034, 12077.335746534025, 14168.858311416754, 14173.574792756233, 14177.858221417653, 14178.432862685377, 12466.439010244561, 13755.724885502234, 14022.22877741734, 14382.928085359716, 14543.27283635814, 5561.805336813192, 5570.053090974544, 5668.234788303526, 5670.659760414388, 5735.304406928737, 1019.7705516258783, 1023.3603997501561, 1025.1337619711333, 1026.2559644258004, 1026.7741096958657, 28804.711952880203, 28809.855950720113, 28904.0, 28969.420611588073, 29206.56190157107, 1069.5454431866426, 1082.626493859617, 1091.8034753744403, 1104.1884215101893, 1127.5546159267208, 14600.34298456572, 15260.542183734506, 15460.690786184214, 15463.768043479356, 15710.371585136696, 300243.0, 301696.0, 302525.0, 302940.0, 303025.0, 48393.758031210324, 48999.0, 55613.721931390086, 56444.0, 63286.367136328045, 1506.4652048522835, 1525.2450037231642, 1529.6175956010998, 1537.3235776258412, 1541.485413257774, 31183.376332473676, 32427.35145297128, 32854.83572582122, 32915.670843291264, 33115.83442082774, 45545.089098218516, 45729.54270457386, 45845.54154458546, 45909.0, 45915.54084459112, 305788.0, 305839.0, 306864.0, 306885.0, 307940.0, 30516.542251866686, 30819.845900770353, 31069.378612427172, 37758.81120594379, 37785.433218502294, 33545.0, 37523.43714844225, 38687.41968870413, 40428.59571404366, 44838.10323793571, 32041.839790800896, 32136.678633214306, 32330.83834580812, 32503.674963250065, 32504.83747581247, 32019.0, 32035.359292813548, 32539.674603253665, 32817.179570510925, 33243.83378083191, 37026.25947481089, 37555.436668449445, 37972.240555189295, 38031.619683802805, 38060.0, 30631.2342191447, 30713.846430767702, 30953.380932380776, 31058.223544411572, 31236.0, 24919.0, 24944.50110997806, 25000.0, 25043.87478062598, 25231.11691090803], "value": 18989.530054953666, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.49958794296117, 469.3124572501285, 473.75128057769643, 484.13340121183086, 487.68300604607003, 19195.904020479895, 20629.07169177387, 20720.0, 20820.47948801279, 20912.477188070287, 21042.684359734616, 21278.0, 22411.55176896461, 22567.435814104654, 22568.210112646062, 6221.066839974006, 6287.591306565058, 6358.459530939876, 6415.711292991803, 6525.347465253484, 6595.868082638344, 6700.597964122144, 6711.731530738764, 6713.093732346144, 7246.23914488979, 7674.769756907302, 7688.269614386624, 7702.46082774206, 7702.46082774206, 7715.768526944201, 7321.743738969137, 7586.317231449196, 7608.695652173907, 7618.809529761745, 7683.884741728879, 7288.344049035559, 7412.406332638455, 7466.775996720107, 7520.398368130536, 7536.095668519758, 24086.87956560206, 24294.0, 24537.754622453725, 24733.134340298184, 24821.751782482126, 3133.9529907051415, 3215.5980502437196, 3245.2211469247445, 3304.306095719902, 3320.518524813913, 15456.304466299043, 16531.83468165327, 16612.58468538284, 16776.07731574763, 16777.496675099643, 2257.8645281283175, 2280.5096904165516, 2372.727136379321, 2387.1286980252085, 2405.482821193452, 114167.0, 116182.41908790403, 116398.41800791027, 117732.41133794362, 121938.0, 17931.641367172582, 17940.55148621295, 18192.181351839197, 18396.540086497946, 18793.906030470032, 31984.68015319817, 32110.197245068593, 32145.839270803965, 32285.417166626612, 32401.35197296088, 10359.22305827066, 10369.118624916966, 10429.426381549089, 10448.73878153037, 10554.788904221872, 8743.956280218685, 8788.42875213109, 8863.202311791842, 8997.865032024525, 9017.954910225408, 8895.332850036273, 9400.106989836091, 9467.195288400426, 9499.857502137473, 9510.239180865514, 3823.101571130838, 3851.1912498375095, 3857.691384689218, 3968.146848427602, 4076.592340765959, 953.1374106433689, 968.9806203875883, 974.4104816586005, 975.8731364922514, 1002.0680766886719, 19979.700304495444, 20281.0, 20523.384298471068, 20604.79395206029, 20837.37487875366, 1104.4146602300632, 1106.623747925709, 1126.622581435212, 1148.0987424871437, 1152.6311580294225, 11012.614558490415, 11178.27341222818, 11260.887391126147, 11329.150313726503, 11381.886181138249, 697131.0, 699575.0, 703180.0, 706544.0, 726784.0, 97727.51136244416, 102308.48845755724, 106484.46757766316, 124574.37712811377, 125925.37037314755, 1333.1534475607864, 1356.19625897558, 1376.5250988408982, 1389.388668985643, 1390.603677951779, 18078.728819067986, 18528.814711853247, 18617.441476755717, 18641.81358186455, 18754.906225468785, 51831.740841295556, 52902.20646690226, 53192.4680753203, 53647.85408583613, 53740.19389709236, 71756.64121679358, 72751.63624181846, 72806.0, 73032.0, 73397.63301183673, 11230.887691122984, 12127.696807579881, 12638.684032899251, 12713.36433178356, 12902.612921612366, 14383.0, 14393.712125757216, 14536.200508971686, 14600.415983360546, 14831.92584037073, 11610.24533405326, 11639.529421172036, 11657.41712914334, 11717.472713728073, 11770.117241207116, 11504.654860354203, 11755.529778809094, 11851.348175850411, 11866.76266474683, 11945.641630751092, 12726.68183295425, 12875.678108047, 12912.8063079052, 12925.353732313535, 12936.611901642966, 11914.106442017057, 11963.820542691692, 12142.271463712206, 12349.444275007467, 12430.0, 12239.08206884505, 12436.564720234748, 12440.564580239648, 12651.87348126507, 12732.299723515296], "value": 11683.075427151802, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.2608769527537, 1570.6151992761775, 1598.2008995502247, 1606.8071831380232, 1609.2275707660315, 9720.319577629569, 10990.340579565222, 11298.15263855211, 11647.767044659124, 11660.41697915103, 11408.771824563524, 11423.600173993911, 11486.368249746261, 11487.195896287243, 11519.942400287986, 2051.69224616308, 2104.4738815296178, 2387.2241521505493, 2409.819263555232, 2415.2754173747894, 2351.400392899817, 2404.5431368040017, 2423.309356833303, 2427.3324835670155, 2428.6357046443086, 2357.033616217351, 2373.857568545884, 2374.905003799846, 2375.762423757628, 2382.273406610983, 2366.3610825077135, 2374.157174203159, 2375.2161786610377, 2375.7624237576106, 2381.416552944522, 2160.902759375832, 2184.541246338271, 2186.8250539956766, 2270.14869423966, 2320.746796729764, 11655.766884662344, 11736.47185876629, 11755.7061073473, 11762.235454695418, 11769.646910592695, 5641.407652196502, 5791.247137872049, 5793.710314484259, 5793.971030144822, 5795.623284486495, 3319.203391186122, 3378.290558982592, 3508.912277193065, 3540.5574303212097, 3561.4301711726, 1328.740895525373, 1329.4246318113067, 1336.5589355512657, 1343.5902049874785, 1360.9863901361057, 18428.999999999865, 18480.722789158175, 18551.257949681996, 18565.44303670892, 18586.349477768214, 4690.33937429398, 4720.787564559603, 4809.134355815917, 4819.614430845524, 4881.877953051131, 14609.92695036518, 14611.342489587994, 14624.634384140269, 14627.488037918834, 14640.487582934546, 5850.707464626752, 5956.255468066492, 6015.879682406327, 6056.394360563909, 6063.787767428119, 4480.887977800516, 4610.308453731967, 5319.946800532022, 5366.597505187048, 5521.199426083195, 3940.802959851996, 4043.4137050128156, 4044.23159599681, 4268.445102136702, 4382.0, 1164.598213616301, 1174.6123779152858, 1188.453311476713, 1204.5783975608467, 1210.2315029956042, 962.956666949989, 965.7489052846311, 971.7279161834698, 981.7496538382667, 983.2920297385871, 6828.2488926218075, 6833.760818371333, 6957.478189135835, 7159.0, 7170.139583250043, 1081.0972837680624, 1084.6766944327908, 1090.1932569898377, 1107.125370956935, 1117.1509652664033, 4433.334999749999, 4445.755483448442, 4447.866564003085, 4513.830136659059, 4515.81936722534, 14824.407023719146, 15010.47463338778, 15168.0, 15189.772153417707, 15229.771553426708, 14416.783748243784, 15037.248137593077, 15063.397464101532, 15077.773833392508, 15082.170480623452, 10705.892941070646, 10849.511771970123, 10873.83689244662, 10935.398553079656, 10936.507857146447, 7234.5659260444545, 7442.330190282901, 7496.62516874165, 7580.1661817198965, 7583.203763604742, 13267.867321326663, 13379.53171638988, 13423.463061477432, 13504.324783760969, 13801.0, 13762.311884405539, 13780.931095344458, 13800.102993305403, 13827.654308642364, 14132.50536231227, 9864.950675246579, 10076.546555404877, 10333.74165645865, 10350.068493835592, 10352.171826254033, 9327.766805829908, 9580.760480987752, 10981.505832237408, 11383.943080284545, 11457.484413201259, 9304.674336398193, 9337.579808908751, 9387.202087822612, 9481.952590237004, 9684.903150968401, 8815.250703690259, 8864.689735859214, 8901.643934242558, 8956.865647015169, 9042.81914361696, 8154.836903261783, 8162.877556836534, 8201.71294004707, 8210.548419836727, 8218.424710270463, 5922.496587790086, 6239.688015599111, 6311.400416960381, 6386.93613063882, 6388.201474815648, 6050.42520960508, 6066.908996365147, 6180.6600636965395, 6230.096635987666, 6261.425959780557], "value": 5421.805248299587, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.4901075633943, 413.87997480730587, 426.78873957391096, 433.6054190686476, 437.6914275435817, 5049.697018178903, 5145.176771716526, 5192.221166824978, 5240.318758561387, 5242.090639694968, 5041.596672266219, 5133.563647089993, 5136.974315128419, 5151.69089854609, 5157.329547158864, 4475.239209334405, 4507.57178068083, 4517.683762136652, 4520.97739511302, 4549.294859296814, 4438.7780610969485, 4452.265376212938, 4462.419885414892, 4545.318202269653, 4551.772411379435, 4496.190685676577, 4501.617362524173, 4505.346724724913, 4515.480719717241, 4532.161550113239, 4497.9775101124615, 4553.33976573395, 4556.54434556545, 4569.04050149469, 4615.376924115269, 4134.263609284108, 4340.761258130801, 4377.5403582623685, 4392.099619578011, 4396.582324679151, 5011.799528018875, 5041.571466425322, 5056.089903817311, 5070.188769796851, 5113.414841399178, 5079.4412614612365, 5124.718820294926, 5131.461196574381, 5146.871328216787, 5199.376074871002, 5287.189152555812, 5458.563314934835, 5498.927709096728, 5508.862278443031, 5510.338759348863, 4817.831375901862, 4835.927461088087, 4837.250226214942, 4844.570851598787, 4846.975765121187, 5763.0, 5773.278340207474, 5775.797847075332, 5791.681457519835, 5810.7385167667135, 4831.444383895827, 4933.210686290176, 4991.750412479361, 5000.324956130876, 5035.647504674637, 4996.825111121093, 5009.0, 5014.6991180529285, 5046.26829109777, 5074.0, 5133.845984620468, 5209.0, 5230.790768369223, 5242.318498595235, 5282.286891269706, 5186.688798672092, 5410.215518749759, 5440.06877558461, 5463.590230732711, 5526.50261476469, 1504.4884739188728, 1535.4395645589502, 1586.0, 1619.336072210388, 1624.382734560883, 1221.1879100398314, 1255.7614053329837, 1267.8605353411117, 1276.117726353621, 1281.1288323939625, 5337.546308563816, 5340.385855626652, 5349.839504814861, 5373.919391209135, 5420.512153906167, 5561.249231353715, 5582.944170558324, 5605.785714642693, 5613.775448981995, 5636.549076073986, 5260.71066091361, 5304.787808487695, 5308.654937429054, 5358.5981051421295, 5396.541293990055, 2921.7516511096796, 2940.0, 2946.3665311958034, 2953.7889465319117, 2954.3500429905384, 5719.025277432228, 5790.739416726257, 5820.650760954271, 5853.414658534198, 5884.6469211847425, 5577.804776832872, 5614.0, 5668.659880407189, 5711.828645140569, 5768.711564421846, 5785.4793068623185, 5787.450192231731, 5799.797007104731, 5802.0, 5819.854503637273, 5214.348206474191, 5550.195221692751, 5576.191452239484, 5581.10702287632, 5599.748011339581, 5522.66863988162, 5528.7235638218735, 5538.778448862, 5542.58664040667, 5559.11054231321, 5755.71221438918, 5824.9806283901, 5836.824895253149, 5874.471297583238, 5903.2325797646035, 4442.800073996613, 4699.318598803223, 4699.718016918996, 4719.126961512131, 4725.881852953704, 5373.919391209213, 5490.725463726878, 5662.631928924607, 5673.347565030074, 5675.971620141873, 5356.544693701079, 5434.809781657781, 5465.321992223702, 5485.396606373375, 5499.615026948075, 5420.186971954238, 5449.6185267032515, 5452.672839629635, 5470.206820011155, 5506.834794956158, 5245.580353571629, 5379.2469054333205, 5425.864353391197, 5463.344398672185, 5490.835274941758, 5428.67427954324, 5456.90861827644, 5499.212755854283, 5501.587380946365, 5513.807016754395, 5239.12304894383, 5333.173358129496, 5358.4105748366865, 5415.564875308125, 5425.213344065168], "value": 4450.214292143384, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.5545822068749, 829.7137487566791, 832.6128350317105, 835.2941764209243, 887.4187407248253, 16884.577885552866, 18433.539161520952, 18445.077746112707, 18490.44528664142, 18597.53506162345, 15502.224888755574, 16057.759133613004, 16601.335946562154, 16880.915595422004, 17112.144392780374, 849.821537477129, 869.3088994249555, 894.4275663575316, 898.8696638987343, 910.6084383715, 912.885889263842, 914.4970266353512, 915.4598786715836, 916.6058594804232, 917.0966597901062, 943.580106852451, 943.8631398447221, 943.8820147481565, 944.3483996042709, 944.3955868244329, 938.6573900526292, 940.1068984464717, 940.6096469964956, 941.4304345870787, 942.6983365323099, 694.836713372357, 695.0165515795144, 697.0206859362553, 697.1982220446477, 698.1099098649212, 16182.109983950879, 16377.918110409491, 16447.753283700753, 16495.175241237892, 16537.255823487973, 17135.91432042844, 17359.524440422578, 17585.472435826945, 17644.382446614436, 18228.36200732981, 10934.343939363664, 11244.325340479598, 11269.887301126966, 11391.487383067783, 11669.474873630706, 4066.79666016698, 4092.631663150331, 4100.36444351126, 4116.567760385146, 4136.31750761124, 53220.73389633066, 54415.0, 61939.0, 62241.68879155575, 91539.0, 8620.525871077025, 11106.777864442827, 12947.287899165449, 12999.610011699664, 13132.146410483288, 24298.392540186283, 24513.63229551558, 24584.631230531555, 24663.630045549333, 24687.135950241656, 8371.623276952552, 9194.862077068849, 9320.30097742672, 9801.607935682636, 9927.15619172364, 5055.34280543527, 5120.820771273062, 5130.333056702605, 5156.61325400589, 5275.076861549213, 3609.187932715118, 3679.632036796299, 3699.038250055005, 3700.7224458165747, 3705.481232627434, 649.6069877724003, 654.4110300729382, 655.0370954696589, 667.9365460281266, 668.3249917583275, 805.7945223967968, 817.5748610722391, 835.2983493865061, 845.9788505287418, 848.5587494502824, 11209.551617935354, 11336.263142895685, 11462.369569673749, 11469.093416890308, 11779.587714429954, 772.1930582541257, 773.4972268025829, 781.3436713160972, 787.7597332813547, 788.7239466186903, 5501.284832971688, 5528.198411230349, 5673.461021203062, 5687.658740475585, 5693.28833895763, 84780.57609712036, 85710.57144714237, 86433.0, 86586.0, 89697.55151224333, 74381.25618743851, 74493.25506744972, 76269.0, 76693.61653191698, 78306.21693783104, 67734.0, 68371.0, 68590.0, 69659.0, 70548.64725676441, 10464.738381540523, 10564.524596393027, 10823.512941917475, 10971.615993440191, 10990.17573681961, 44665.33002004908, 45167.77416112898, 45183.32225016561, 46111.0, 46224.30663539982, 54493.45506545043, 54784.17823732567, 55453.7227313877, 55553.444465556444, 55970.0, 12645.0, 12751.426185821474, 12965.935170324088, 13166.275854828076, 13205.735885282049, 16495.505134845975, 16707.66584668324, 16782.916085419496, 16852.0, 18725.438236852915, 16104.194790260675, 16536.917315413826, 16617.50147495577, 17087.487375378758, 17393.478195654152, 15284.847151528342, 15673.216339183224, 15739.291731872323, 15888.682226355177, 16079.0, 15957.920210398874, 15990.760138597698, 16100.516984490485, 16587.0, 16786.748198777274, 11244.943775281072, 11490.195686301879, 11536.423178841193, 11780.234284771463, 12038.75922481528, 12289.0, 12305.753884922431, 12331.198472099644, 12556.121071524905, 12562.623121306375], "value": 7949.142941040488, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 113.0, 113.0, 113.0, 111.0, 113.0, 112.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 116.0, 157.0, 117.0, 117.0, 116.0, 114.0, 130.0, 117.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 163.0, 162.0, 162.0, 161.0, 162.0, 163.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 112.0, 77.0, 75.0, 75.0, 75.0, 89.0, 76.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 110.0, 82.0, 81.0, 82.0, 91.0, 81.0, 81.0, 111.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 112.0, 113.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 55.0, 53.0, 55.0, 54.0, 54.0, 64.0, 53.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 64.0, 57.0, 53.0, 53.0, 54.0, 53.0, 53.0, 53.0, 52.0], "value": 54.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 79.0, 79.0, 80.0, 82.0, 86.0, 80.0, 74.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 121.0, 123.0, 123.0, 123.0, 127.0, 119.0, 121.0, 163.0, 119.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 159.0, 160.0, 157.0, 157.0, 166.0, 169.0, 169.0, 162.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 58.0, 59.0], "value": 59.4, "unit": "score"}]}]} 03:20:58 INFO - Return code: 0 03:20:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:20:59 ERROR - # TBPL SUCCESS # 03:20:59 INFO - Running post-action listener: _resource_record_post_action 03:20:59 INFO - [mozharness: 2017-04-06 10:20:59.009137Z] Finished run-tests step (success) 03:20:59 INFO - Running post-run listener: _resource_record_post_run 03:20:59 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 266240; Write bytes: 365801472; Read time: 24; Write time: 333320 03:20:59 INFO - TinderboxPrint: CPU usage
13.1% 03:20:59 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 03:20:59 INFO - TinderboxPrint: I/O write bytes / time
365,801,472 / 333,320 03:20:59 INFO - TinderboxPrint: CPU idle
8,269.7 (86.8%) 03:20:59 INFO - TinderboxPrint: CPU user
1,202.9 (12.6%) 03:20:59 INFO - TinderboxPrint: Swap in / out
0 / 0 03:20:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111140864; Read time: 0; Write time: 152696 03:20:59 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 266240; Write bytes: 254660608; Read time: 24; Write time: 180624 03:20:59 INFO - Running post-run listener: _upload_blobber_files 03:20:59 INFO - Blob upload gear active. 03:20:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:20: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:20: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:20: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:21:00 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:21:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:21:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:00 INFO - (blobuploader) - INFO - Done attempting. 03:21:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:21:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:21:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:04 INFO - (blobuploader) - INFO - Done attempting. 03:21:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:21:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:21:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:05 INFO - (blobuploader) - INFO - Done attempting. 03:21:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:21:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:21:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:06 INFO - (blobuploader) - INFO - Done attempting. 03:21:06 INFO - (blobuploader) - INFO - Iteration through files over. 03:21:06 INFO - Return code: 0 03:21:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:21:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69875c6671554ee488b4083a1d88a2c8a08b57a821c726ad1cd097268185b94cfd35d20ffbc293a175c926524f3c8955218fbb14635a1891d77044f51f8426c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af55b196925a0639f1533773ba688a7fdbfd3c060bde679279702b293bbcc573f26538e456c634e1bfd3640a932afd9d53e8c8318e4189f489926bdd2c8cbc5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6f8c3802451cd5ff0d7d3ae790e51cf166319bdc4eb44077bde79ff624bb581d4524a585ff092d24c9297b4ec9a7ba3fbc89b0c0a808daedb500024bcdd2b69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a9ec728d0690184ee166dfd7440ea1af09719b0097f898a57274204b78f21041e8bb20e6b1f2887576599e77920fd16d96b6c74c27c65ae1a9ab76da9662ff7"} 03:21:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:21:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:21:06 INFO - Contents: 03:21:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69875c6671554ee488b4083a1d88a2c8a08b57a821c726ad1cd097268185b94cfd35d20ffbc293a175c926524f3c8955218fbb14635a1891d77044f51f8426c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af55b196925a0639f1533773ba688a7fdbfd3c060bde679279702b293bbcc573f26538e456c634e1bfd3640a932afd9d53e8c8318e4189f489926bdd2c8cbc5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6f8c3802451cd5ff0d7d3ae790e51cf166319bdc4eb44077bde79ff624bb581d4524a585ff092d24c9297b4ec9a7ba3fbc89b0c0a808daedb500024bcdd2b69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a9ec728d0690184ee166dfd7440ea1af09719b0097f898a57274204b78f21041e8bb20e6b1f2887576599e77920fd16d96b6c74c27c65ae1a9ab76da9662ff7"} 03:21:06 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:06 INFO - Copying logs to upload dir... 03:21:06 INFO - mkdir: /builds/slave/test/build/upload/logs 03:21:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.088366 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-04-06 03:21:06.051327) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 03:21:06.055035) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940 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/69875c6671554ee488b4083a1d88a2c8a08b57a821c726ad1cd097268185b94cfd35d20ffbc293a175c926524f3c8955218fbb14635a1891d77044f51f8426c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af55b196925a0639f1533773ba688a7fdbfd3c060bde679279702b293bbcc573f26538e456c634e1bfd3640a932afd9d53e8c8318e4189f489926bdd2c8cbc5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6f8c3802451cd5ff0d7d3ae790e51cf166319bdc4eb44077bde79ff624bb581d4524a585ff092d24c9297b4ec9a7ba3fbc89b0c0a808daedb500024bcdd2b69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a9ec728d0690184ee166dfd7440ea1af09719b0097f898a57274204b78f21041e8bb20e6b1f2887576599e77920fd16d96b6c74c27c65ae1a9ab76da9662ff7"} build_url:https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011216 build_url: 'https://queue.taskcluster.net/v1/task/NBjVbMfURpieEOnuOoiP2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69875c6671554ee488b4083a1d88a2c8a08b57a821c726ad1cd097268185b94cfd35d20ffbc293a175c926524f3c8955218fbb14635a1891d77044f51f8426c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af55b196925a0639f1533773ba688a7fdbfd3c060bde679279702b293bbcc573f26538e456c634e1bfd3640a932afd9d53e8c8318e4189f489926bdd2c8cbc5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6f8c3802451cd5ff0d7d3ae790e51cf166319bdc4eb44077bde79ff624bb581d4524a585ff092d24c9297b4ec9a7ba3fbc89b0c0a808daedb500024bcdd2b69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a9ec728d0690184ee166dfd7440ea1af09719b0097f898a57274204b78f21041e8bb20e6b1f2887576599e77920fd16d96b6c74c27c65ae1a9ab76da9662ff7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 03:21:06.080347) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:21:06.080660) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1491472803.996385-121633940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.86 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:21:06.943788) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 03:21:06.944120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 03:21:06.944762) ========= ========= Total master_lag: 0.94 =========