builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-001 starttime: 1492936570.19 results: success (0) revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.187417) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.187921) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.188228) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.240562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.240900) ========= 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=8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-23 01:36:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2017-04-23 01:36:10 (228 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.189656 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.460606) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.461047) ========= 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=8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.083804 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.558486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:10.558842) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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=8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-23 01:36:10,831 truncating revision to first 12 chars 2017-04-23 01:36:10,831 Setting DEBUG logging. 2017-04-23 01:36:10,831 attempt 1/10 2017-04-23 01:36:10,831 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-23 01:36:11,491 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.964709 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:11.538219) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:11.538527) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:11.538940) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:11.539230) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-23 01:36:11.557358) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-04-23 01:36:11.557706) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:36:12 INFO - MultiFileLogger online at 20170423 01:36:12 in /builds/slave/test 01:36:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 01:36:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:36:12 INFO - {'append_to_log': False, 01:36:12 INFO - 'base_work_dir': '/builds/slave/test', 01:36:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 01:36:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:36:12 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 01:36:12 INFO - 'buildbot_json_path': 'buildprops.json', 01:36:12 INFO - 'config_files': ('talos/linux_config.py',), 01:36:12 INFO - 'default_actions': ('clobber', 01:36:12 INFO - 'read-buildbot-config', 01:36:12 INFO - 'download-and-extract', 01:36:12 INFO - 'populate-webroot', 01:36:12 INFO - 'create-virtualenv', 01:36:12 INFO - 'install', 01:36:12 INFO - 'run-tests'), 01:36:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:36:12 INFO - 'download_minidump_stackwalk': True, 01:36:12 INFO - 'download_symbols': 'ondemand', 01:36:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:36:12 INFO - 'tooltool.py': '/tools/tooltool.py', 01:36:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:36:12 INFO - '/tools/misc-python/virtualenv.py')}, 01:36:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:36:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:36:12 INFO - 'gecko_profile': False, 01:36:12 INFO - 'gecko_profile_interval': 0, 01:36:12 INFO - 'installer_path': 'installer.exe', 01:36:12 INFO - 'log_level': 'info', 01:36:12 INFO - 'log_name': 'talos', 01:36:12 INFO - 'log_to_console': True, 01:36:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:36:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:36:12 INFO - 'opt_config_files': (), 01:36:12 INFO - 'pip_index': False, 01:36:12 INFO - 'suite': 'dromaeojs', 01:36:12 INFO - 'system_bits': '32', 01:36:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:36:12 INFO - 'title': 'talos-linux64-ix-001', 01:36:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:36:12 INFO - 'use_talos_json': True, 01:36:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:36:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:36:12 INFO - 'work_dir': 'build'} 01:36:12 INFO - [mozharness: 2017-04-23 08:36:12.034773Z] Running clobber step. 01:36:12 INFO - Running pre-action listener: _resource_record_pre_action 01:36:12 INFO - Running main action method: clobber 01:36:12 INFO - rmtree: /builds/slave/test/build 01:36:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:36:17 INFO - Running post-action listener: _resource_record_post_action 01:36:17 INFO - [mozharness: 2017-04-23 08:36:17.341263Z] Finished clobber step (success) 01:36:17 INFO - [mozharness: 2017-04-23 08:36:17.341428Z] Running read-buildbot-config step. 01:36:17 INFO - Running pre-action listener: _resource_record_pre_action 01:36:17 INFO - Running main action method: read_buildbot_config 01:36:17 INFO - Using buildbot properties: 01:36:17 INFO - { 01:36:17 INFO - "project": "", 01:36:17 INFO - "product": "firefox", 01:36:17 INFO - "who": "nobody@mozilla.org", 01:36:17 INFO - "installer_path": "public/build/target.tar.bz2", 01:36:17 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:36:17 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora", 01:36:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 01:36:17 INFO - "stage_platform": "linux64", 01:36:17 INFO - "basedir": "/builds/slave/test", 01:36:17 INFO - "buildnumber": 138, 01:36:17 INFO - "platform": "ubuntu64_hw", 01:36:17 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:36:17 INFO - "slavebuilddir": "test", 01:36:17 INFO - "taskId": "Oj65yy5pT4OUTQuAnVKLeA", 01:36:17 INFO - "branch": "mozilla-aurora", 01:36:17 INFO - "script_repo_revision": "production", 01:36:17 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:36:17 INFO - "slavename": "talos-linux64-ix-001", 01:36:17 INFO - "repo_path": "releases/mozilla-aurora" 01:36:17 INFO - } 01:36:17 INFO - Finding installer, test and symbols from parent task. 01:36:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oj65yy5pT4OUTQuAnVKLeA'}, attempt #1 01:36:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oj65yy5pT4OUTQuAnVKLeA'}, attempt #1 01:36:18 INFO - Task dependencies: Kf10F7y9THCY9AYof-ogXw 01:36:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw'}, attempt #1 01:36:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.tar.bz2 01:36:18 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/test_packages.json 01:36:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.crashreporter-symbols.zip 01:36:18 INFO - Running post-action listener: _resource_record_post_action 01:36:18 INFO - [mozharness: 2017-04-23 08:36:18.692502Z] Finished read-buildbot-config step (success) 01:36:18 INFO - [mozharness: 2017-04-23 08:36:18.692722Z] Running download-and-extract step. 01:36:18 INFO - Running pre-action listener: _resource_record_pre_action 01:36:18 INFO - Running main action method: download_and_extract 01:36:18 INFO - mkdir: /builds/slave/test/build/tests 01:36:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:18 INFO - trying https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.test_packages.json 01:36:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:36:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:36:19 INFO - Downloaded 896 bytes. 01:36:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:36:19 INFO - Using the following test package requirements: 01:36:19 INFO - {u'common': [u'target.common.tests.zip'], 01:36:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:36:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:36:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:36:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:36:19 INFO - u'mozbase': [u'target.common.tests.zip'], 01:36:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:36:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:36:19 INFO - u'web-platform': [u'target.common.tests.zip', 01:36:19 INFO - u'target.web-platform.tests.zip'], 01:36:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:36:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:36:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.common.tests.zip 01:36:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:36:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.common.tests.zip into memory 01:36:20 INFO - Expected file size: 59542262 01:36:20 INFO - Obtained file size: 59542262 01:36:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:36:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.common.tests.zip 01:36:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:36:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.common.tests.zip into memory 01:36:31 INFO - Expected file size: 59542262 01:36:31 INFO - Obtained file size: 59542262 01:36:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.talos.tests.zip 01:36:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:36:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.talos.tests.zip into memory 01:36:43 INFO - Expected file size: 14404424 01:36:43 INFO - Obtained file size: 14404424 01:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:43 INFO - trying https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.tar.bz2 01:36:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:36:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:36:44 INFO - Downloaded 66728785 bytes. 01:36:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.tar.bz2 01:36:44 INFO - mkdir: /builds/slave/test/properties 01:36:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:36:44 INFO - Writing to file /builds/slave/test/properties/build_url 01:36:44 INFO - Contents: 01:36:44 INFO - build_url:https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.tar.bz2 01:36:44 INFO - Running post-action listener: _resource_record_post_action 01:36:44 INFO - Running post-action listener: set_extra_try_arguments 01:36:44 INFO - [mozharness: 2017-04-23 08:36:44.466384Z] Finished download-and-extract step (success) 01:36:44 INFO - [mozharness: 2017-04-23 08:36:44.466482Z] Running populate-webroot step. 01:36:44 INFO - Running pre-action listener: _resource_record_pre_action 01:36:44 INFO - Running main action method: populate_webroot 01:36:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:36:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:36:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:36:44 INFO - '--tppagecycles', 01:36:44 INFO - '7'], 01:36:44 INFO - 'tests': ['tsvgm']}}, 01:36:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:36:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:36:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:36:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:36:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:36:44 INFO - 'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:36:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:36:44 INFO - 'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['damp', 'tps']}, 01:36:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:36:44 INFO - 'tests': ['damp', 'tps']}, 01:36:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['dromaeo_dom']}, 01:36:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:36:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['basic_compositor_video']}, 01:36:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:36:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['a11yr', 01:36:44 INFO - 'ts_paint', 01:36:44 INFO - 'tpaint', 01:36:44 INFO - 'sessionrestore', 01:36:44 INFO - 'sessionrestore_no_auto_restore', 01:36:44 INFO - 'tabpaint']}, 01:36:44 INFO - 'other-e10s': {'tests': ['a11yr', 01:36:44 INFO - 'ts_paint', 01:36:44 INFO - 'tpaint', 01:36:44 INFO - 'sessionrestore', 01:36:44 INFO - 'sessionrestore_no_auto_restore', 01:36:44 INFO - 'tabpaint']}, 01:36:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:36:44 INFO - 'ts_paint', 01:36:44 INFO - 'tpaint', 01:36:44 INFO - 'sessionrestore', 01:36:44 INFO - 'sessionrestore_no_auto_restore', 01:36:44 INFO - 'tabpaint']}, 01:36:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:36:44 INFO - 'ts_paint', 01:36:44 INFO - 'tpaint', 01:36:44 INFO - 'sessionrestore', 01:36:44 INFO - 'sessionrestore_no_auto_restore', 01:36:44 INFO - 'tabpaint']}, 01:36:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['a11yr', 01:36:44 INFO - 'ts_paint', 01:36:44 INFO - 'tpaint', 01:36:44 INFO - 'sessionrestore', 01:36:44 INFO - 'sessionrestore_no_auto_restore', 01:36:44 INFO - 'tabpaint']}, 01:36:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['a11yr', 01:36:44 INFO - 'ts_paint', 01:36:44 INFO - 'tpaint', 01:36:44 INFO - 'sessionrestore', 01:36:44 INFO - 'sessionrestore_no_auto_restore', 01:36:44 INFO - 'tabpaint']}, 01:36:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['tsvgx', 01:36:44 INFO - 'tsvgr_opacity', 01:36:44 INFO - 'tart', 01:36:44 INFO - 'tscrollx', 01:36:44 INFO - 'cart', 01:36:44 INFO - 'tsvg_static']}, 01:36:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:36:44 INFO - 'tsvgr_opacity', 01:36:44 INFO - 'tart', 01:36:44 INFO - 'tscrollx', 01:36:44 INFO - 'cart', 01:36:44 INFO - 'tsvg_static']}, 01:36:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:36:44 INFO - 'talos_options': ['--disable-e10s'], 01:36:44 INFO - 'tests': ['tp5o']}, 01:36:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:36:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:36:44 INFO - 'talos_options': ['--disable-e10s', 01:36:44 INFO - '--xperf_path', 01:36:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:44 INFO - 'tests': ['tp5n']}, 01:36:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:36:44 INFO - 'talos_options': ['--xperf_path', 01:36:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:44 INFO - 'tests': ['tp5n']}}, 01:36:44 INFO - 'talos.zip': {'path': '', 01:36:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:36:44 INFO - Running post-action listener: _resource_record_post_action 01:36:44 INFO - [mozharness: 2017-04-23 08:36:44.479692Z] Finished populate-webroot step (success) 01:36:44 INFO - [mozharness: 2017-04-23 08:36:44.479782Z] Running create-virtualenv step. 01:36:44 INFO - Running pre-action listener: _resource_record_pre_action 01:36:44 INFO - Running main action method: create_virtualenv 01:36:44 INFO - Creating virtualenv /builds/slave/test/build/venv 01:36:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:36:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:36:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:36:44 INFO - Using real prefix '/usr' 01:36:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:36:45 INFO - Installing distribute.............................................................................................................................................................................................done. 01:36:47 INFO - Installing pip.................done. 01:36:47 INFO - Return code: 0 01:36:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:36:47 INFO - Reading from file tmpfile_stdout 01:36:47 INFO - Output received: 01:36:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:36:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:36:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:36:47 INFO - Using env: {'DISPLAY': ':0', 01:36:47 INFO - 'HOME': '/home/cltbld', 01:36:47 INFO - 'LANG': 'en_US.UTF-8', 01:36:47 INFO - 'LANGUAGE': 'en_US:en', 01:36:47 INFO - 'LOGNAME': 'cltbld', 01:36:47 INFO - 'MAIL': '/var/mail/cltbld', 01:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:47 INFO - 'MOZ_NO_REMOTE': '1', 01:36:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:47 INFO - 'NO_EM_RESTART': '1', 01:36:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:47 INFO - 'PWD': '/builds/slave/test', 01:36:47 INFO - 'SHELL': '/bin/bash', 01:36:47 INFO - 'SHLVL': '1', 01:36:47 INFO - 'TERM': 'linux', 01:36:47 INFO - 'TMOUT': '86400', 01:36:47 INFO - 'USER': 'cltbld', 01:36:47 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:47 INFO - '_': '/tools/buildbot/bin/python'} 01:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:36:47 INFO - Cleaning up... 01:36:47 INFO - Return code: 0 01:36:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:36:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:36:47 INFO - Using env: {'DISPLAY': ':0', 01:36:47 INFO - 'HOME': '/home/cltbld', 01:36:47 INFO - 'LANG': 'en_US.UTF-8', 01:36:47 INFO - 'LANGUAGE': 'en_US:en', 01:36:47 INFO - 'LOGNAME': 'cltbld', 01:36:47 INFO - 'MAIL': '/var/mail/cltbld', 01:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:47 INFO - 'MOZ_NO_REMOTE': '1', 01:36:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:47 INFO - 'NO_EM_RESTART': '1', 01:36:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:47 INFO - 'PWD': '/builds/slave/test', 01:36:47 INFO - 'SHELL': '/bin/bash', 01:36:47 INFO - 'SHLVL': '1', 01:36:47 INFO - 'TERM': 'linux', 01:36:47 INFO - 'TMOUT': '86400', 01:36:47 INFO - 'USER': 'cltbld', 01:36:47 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:47 INFO - '_': '/tools/buildbot/bin/python'} 01:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:47 INFO - Downloading/unpacking psutil>=3.1.1 01:36:47 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 01:36:47 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 01:36:47 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 01:36:47 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 01:36:47 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 01:36:47 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 01:36:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:36:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:50 INFO - Installing collected packages: psutil 01:36:50 INFO - Running setup.py install for psutil 01:36:50 INFO - building 'psutil._psutil_linux' extension 01:36:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:36:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:36:51 INFO - building 'psutil._psutil_posix' extension 01:36:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 01:36:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:36:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:51 INFO - Successfully installed psutil 01:36:51 INFO - Cleaning up... 01:36:51 INFO - Return code: 0 01:36:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:36: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')]} 01:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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')]} 01:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:36:51 INFO - Using env: {'DISPLAY': ':0', 01:36:51 INFO - 'HOME': '/home/cltbld', 01:36:51 INFO - 'LANG': 'en_US.UTF-8', 01:36:51 INFO - 'LANGUAGE': 'en_US:en', 01:36:51 INFO - 'LOGNAME': 'cltbld', 01:36:51 INFO - 'MAIL': '/var/mail/cltbld', 01:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:51 INFO - 'MOZ_NO_REMOTE': '1', 01:36:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:51 INFO - 'NO_EM_RESTART': '1', 01:36:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:51 INFO - 'PWD': '/builds/slave/test', 01:36:51 INFO - 'SHELL': '/bin/bash', 01:36:51 INFO - 'SHLVL': '1', 01:36:51 INFO - 'TERM': 'linux', 01:36:51 INFO - 'TMOUT': '86400', 01:36:51 INFO - 'USER': 'cltbld', 01:36:51 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:51 INFO - '_': '/tools/buildbot/bin/python'} 01:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:36: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 01:36: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 01:36: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 01:36: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 01:36: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 01:36: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 01:36:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:36:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:36:53 INFO - Installing collected packages: mozsystemmonitor 01:36:53 INFO - Running setup.py install for mozsystemmonitor 01:36:53 INFO - Successfully installed mozsystemmonitor 01:36:53 INFO - Cleaning up... 01:36:53 INFO - Return code: 0 01:36:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:36:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:36:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:36:54 INFO - Using env: {'DISPLAY': ':0', 01:36:54 INFO - 'HOME': '/home/cltbld', 01:36:54 INFO - 'LANG': 'en_US.UTF-8', 01:36:54 INFO - 'LANGUAGE': 'en_US:en', 01:36:54 INFO - 'LOGNAME': 'cltbld', 01:36:54 INFO - 'MAIL': '/var/mail/cltbld', 01:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:54 INFO - 'MOZ_NO_REMOTE': '1', 01:36:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:54 INFO - 'NO_EM_RESTART': '1', 01:36:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:54 INFO - 'PWD': '/builds/slave/test', 01:36:54 INFO - 'SHELL': '/bin/bash', 01:36:54 INFO - 'SHLVL': '1', 01:36:54 INFO - 'TERM': 'linux', 01:36:54 INFO - 'TMOUT': '86400', 01:36:54 INFO - 'USER': 'cltbld', 01:36:54 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:54 INFO - '_': '/tools/buildbot/bin/python'} 01:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:54 INFO - Downloading/unpacking jsonschema==2.5.1 01:36: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 01:36: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 01:36: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 01:36: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 01:36: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 01:36: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 01:36:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:36:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:36: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 01:36: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 01:36: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 01:36: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 01:36: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 01:36: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 01:36:56 INFO - Downloading functools32-3.2.3-2.tar.gz 01:36:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:36:56 INFO - warning: no files found matching '*.txt' 01:36:56 INFO - no previously-included directories found matching 'build' 01:36:56 INFO - no previously-included directories found matching 'dist' 01:36:56 INFO - no previously-included directories found matching '.git*' 01:36:56 INFO - Installing collected packages: jsonschema, functools32 01:36:56 INFO - Running setup.py install for functools32 01:36:56 INFO - warning: no files found matching '*.txt' 01:36:56 INFO - no previously-included directories found matching 'build' 01:36:56 INFO - no previously-included directories found matching 'dist' 01:36:56 INFO - no previously-included directories found matching '.git*' 01:36:56 INFO - Successfully installed jsonschema functools32 01:36:56 INFO - Cleaning up... 01:36:56 INFO - Return code: 0 01:36:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:36:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:36:56 INFO - Using env: {'DISPLAY': ':0', 01:36:56 INFO - 'HOME': '/home/cltbld', 01:36:56 INFO - 'LANG': 'en_US.UTF-8', 01:36:56 INFO - 'LANGUAGE': 'en_US:en', 01:36:56 INFO - 'LOGNAME': 'cltbld', 01:36:56 INFO - 'MAIL': '/var/mail/cltbld', 01:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:56 INFO - 'MOZ_NO_REMOTE': '1', 01:36:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:56 INFO - 'NO_EM_RESTART': '1', 01:36:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:56 INFO - 'PWD': '/builds/slave/test', 01:36:56 INFO - 'SHELL': '/bin/bash', 01:36:56 INFO - 'SHLVL': '1', 01:36:56 INFO - 'TERM': 'linux', 01:36:56 INFO - 'TMOUT': '86400', 01:36:56 INFO - 'USER': 'cltbld', 01:36:56 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:56 INFO - '_': '/tools/buildbot/bin/python'} 01:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:36:56 INFO - Cleaning up... 01:36:56 INFO - Return code: 0 01:36:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:36:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:36:56 INFO - Using env: {'DISPLAY': ':0', 01:36:56 INFO - 'HOME': '/home/cltbld', 01:36:56 INFO - 'LANG': 'en_US.UTF-8', 01:36:56 INFO - 'LANGUAGE': 'en_US:en', 01:36:56 INFO - 'LOGNAME': 'cltbld', 01:36:56 INFO - 'MAIL': '/var/mail/cltbld', 01:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:56 INFO - 'MOZ_NO_REMOTE': '1', 01:36:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:56 INFO - 'NO_EM_RESTART': '1', 01:36:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:56 INFO - 'PWD': '/builds/slave/test', 01:36:56 INFO - 'SHELL': '/bin/bash', 01:36:56 INFO - 'SHLVL': '1', 01:36:56 INFO - 'TERM': 'linux', 01:36:56 INFO - 'TMOUT': '86400', 01:36:56 INFO - 'USER': 'cltbld', 01:36:56 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:56 INFO - '_': '/tools/buildbot/bin/python'} 01:36:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:57 INFO - Downloading/unpacking blobuploader==1.2.4 01:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:59 INFO - Downloading blobuploader-1.2.4.tar.gz 01:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:36:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:36:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:59 INFO - Downloading docopt-0.6.1.tar.gz 01:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:36:59 INFO - Installing collected packages: blobuploader, requests, docopt 01:36:59 INFO - Running setup.py install for blobuploader 01:37:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:37:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:37:00 INFO - Running setup.py install for requests 01:37:00 INFO - Running setup.py install for docopt 01:37:00 INFO - Successfully installed blobuploader requests docopt 01:37:00 INFO - Cleaning up... 01:37:00 INFO - Return code: 0 01:37:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:37:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:37:00 INFO - Using env: {'DISPLAY': ':0', 01:37:00 INFO - 'HOME': '/home/cltbld', 01:37:00 INFO - 'LANG': 'en_US.UTF-8', 01:37:00 INFO - 'LANGUAGE': 'en_US:en', 01:37:00 INFO - 'LOGNAME': 'cltbld', 01:37:00 INFO - 'MAIL': '/var/mail/cltbld', 01:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:00 INFO - 'MOZ_NO_REMOTE': '1', 01:37:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:00 INFO - 'NO_EM_RESTART': '1', 01:37:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:00 INFO - 'PWD': '/builds/slave/test', 01:37:00 INFO - 'SHELL': '/bin/bash', 01:37:00 INFO - 'SHLVL': '1', 01:37:00 INFO - 'TERM': 'linux', 01:37:00 INFO - 'TMOUT': '86400', 01:37:00 INFO - 'USER': 'cltbld', 01:37:00 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:00 INFO - '_': '/tools/buildbot/bin/python'} 01:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:00 INFO - Running setup.py (path:/tmp/pip-MUzxnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:00 INFO - Running setup.py (path:/tmp/pip-YV49Op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:00 INFO - Running setup.py (path:/tmp/pip-nGQotk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:00 INFO - Running setup.py (path:/tmp/pip-R0uaQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:01 INFO - Running setup.py (path:/tmp/pip-p5GBrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:01 INFO - Running setup.py (path:/tmp/pip-XVLNz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:01 INFO - Running setup.py (path:/tmp/pip-LUdf3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:01 INFO - Running setup.py (path:/tmp/pip-QmNDxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:01 INFO - Running setup.py (path:/tmp/pip-u_IScv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:01 INFO - Running setup.py (path:/tmp/pip-b6AdC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:01 INFO - Running setup.py (path:/tmp/pip-1B64bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:37:01 INFO - Running setup.py (path:/tmp/pip-T01sH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:37:01 INFO - Running setup.py (path:/tmp/pip-EsDXcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:01 INFO - Running setup.py (path:/tmp/pip-ROgDI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:01 INFO - Running setup.py (path:/tmp/pip-ywcCfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:01 INFO - Running setup.py (path:/tmp/pip-p6ImLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:01 INFO - Running setup.py (path:/tmp/pip-4nylGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:37:01 INFO - Running setup.py install for manifestparser 01:37:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Running setup.py install for mozcrash 01:37:02 INFO - Running setup.py install for mozdebug 01:37:02 INFO - Running setup.py install for mozdevice 01:37:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Running setup.py install for mozfile 01:37:02 INFO - Running setup.py install for mozhttpd 01:37:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Running setup.py install for mozinfo 01:37:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Running setup.py install for mozInstall 01:37:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Running setup.py install for mozleak 01:37:02 INFO - Running setup.py install for mozlog 01:37:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:37:03 INFO - Running setup.py install for moznetwork 01:37:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:37:03 INFO - Running setup.py install for mozprocess 01:37:03 INFO - Running setup.py install for mozprofile 01:37:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:37:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:37:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:37:03 INFO - Running setup.py install for mozrunner 01:37:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:37:03 INFO - Running setup.py install for mozscreenshot 01:37:03 INFO - Running setup.py install for moztest 01:37:03 INFO - Running setup.py install for mozversion 01:37:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:37:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:37:03 INFO - Cleaning up... 01:37:03 INFO - Return code: 0 01:37:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:37:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:37:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:37:03 INFO - Using env: {'DISPLAY': ':0', 01:37:03 INFO - 'HOME': '/home/cltbld', 01:37:03 INFO - 'LANG': 'en_US.UTF-8', 01:37:03 INFO - 'LANGUAGE': 'en_US:en', 01:37:03 INFO - 'LOGNAME': 'cltbld', 01:37:03 INFO - 'MAIL': '/var/mail/cltbld', 01:37:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:03 INFO - 'MOZ_NO_REMOTE': '1', 01:37:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:03 INFO - 'NO_EM_RESTART': '1', 01:37:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:03 INFO - 'PWD': '/builds/slave/test', 01:37:03 INFO - 'SHELL': '/bin/bash', 01:37:03 INFO - 'SHLVL': '1', 01:37:03 INFO - 'TERM': 'linux', 01:37:03 INFO - 'TMOUT': '86400', 01:37:03 INFO - 'USER': 'cltbld', 01:37:03 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:37:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:03 INFO - '_': '/tools/buildbot/bin/python'} 01:37:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:03 INFO - Running setup.py (path:/tmp/pip-sagjKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:04 INFO - Running setup.py (path:/tmp/pip-WgpbP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:04 INFO - Running setup.py (path:/tmp/pip-nMnaGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:04 INFO - Running setup.py (path:/tmp/pip-GlT_3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:04 INFO - Running setup.py (path:/tmp/pip-3Ui6fP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:04 INFO - Running setup.py (path:/tmp/pip-Q_Zdtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:04 INFO - Running setup.py (path:/tmp/pip-clyZ3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:04 INFO - Running setup.py (path:/tmp/pip-cdwNCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:04 INFO - Running setup.py (path:/tmp/pip-k3oCph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:04 INFO - Running setup.py (path:/tmp/pip-uTgxI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:04 INFO - Running setup.py (path:/tmp/pip-cxZ2CM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:37:04 INFO - Running setup.py (path:/tmp/pip-W1dkLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:37:04 INFO - Running setup.py (path:/tmp/pip-0gL6Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:04 INFO - Running setup.py (path:/tmp/pip-5ru25X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:05 INFO - Running setup.py (path:/tmp/pip-oJWM_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:05 INFO - Running setup.py (path:/tmp/pip-wWhKTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:05 INFO - Running setup.py (path:/tmp/pip-jDSehA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:37:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:07 INFO - Downloading blessings-1.6.tar.gz 01:37:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:37:07 INFO - Installing collected packages: blessings 01:37:07 INFO - Running setup.py install for blessings 01:37:07 INFO - Successfully installed blessings 01:37:07 INFO - Cleaning up... 01:37:07 INFO - Return code: 0 01:37:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:37:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:37:07 INFO - Reading from file tmpfile_stdout 01:37:07 INFO - Current package versions: 01:37:07 INFO - argparse == 1.2.1 01:37:07 INFO - blessings == 1.6 01:37:07 INFO - blobuploader == 1.2.4 01:37:07 INFO - docopt == 0.6.1 01:37:07 INFO - functools32 == 3.2.3-2 01:37:07 INFO - jsonschema == 2.5.1 01:37:07 INFO - manifestparser == 1.1 01:37:07 INFO - mozInstall == 1.12 01:37:07 INFO - mozcrash == 1.0 01:37:07 INFO - mozdebug == 0.1 01:37:07 INFO - mozdevice == 0.49 01:37:07 INFO - mozfile == 1.2 01:37:07 INFO - mozhttpd == 0.7 01:37:07 INFO - mozinfo == 0.9 01:37:07 INFO - mozleak == 0.1 01:37:07 INFO - mozlog == 3.4 01:37:07 INFO - moznetwork == 0.27 01:37:07 INFO - mozprocess == 0.25 01:37:07 INFO - mozprofile == 0.28 01:37:07 INFO - mozrunner == 6.13 01:37:07 INFO - mozscreenshot == 0.1 01:37:07 INFO - mozsystemmonitor == 0.3 01:37:07 INFO - moztest == 0.8 01:37:07 INFO - mozversion == 1.4 01:37:07 INFO - psutil == 3.1.1 01:37:07 INFO - requests == 1.2.3 01:37:07 INFO - wsgiref == 0.1.2 01:37:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:37:07 INFO - Using env: {'DISPLAY': ':0', 01:37:07 INFO - 'HOME': '/home/cltbld', 01:37:07 INFO - 'LANG': 'en_US.UTF-8', 01:37:07 INFO - 'LANGUAGE': 'en_US:en', 01:37:07 INFO - 'LOGNAME': 'cltbld', 01:37:07 INFO - 'MAIL': '/var/mail/cltbld', 01:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:07 INFO - 'MOZ_NO_REMOTE': '1', 01:37:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:07 INFO - 'NO_EM_RESTART': '1', 01:37:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:07 INFO - 'PWD': '/builds/slave/test', 01:37:07 INFO - 'SHELL': '/bin/bash', 01:37:07 INFO - 'SHLVL': '1', 01:37:07 INFO - 'TERM': 'linux', 01:37:07 INFO - 'TMOUT': '86400', 01:37:07 INFO - 'USER': 'cltbld', 01:37:07 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:07 INFO - '_': '/tools/buildbot/bin/python'} 01:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:37:07 INFO - Cleaning up... 01:37:07 INFO - Return code: 0 01:37:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, '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': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', '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 01:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:37:07 INFO - Using env: {'DISPLAY': ':0', 01:37:07 INFO - 'HOME': '/home/cltbld', 01:37:07 INFO - 'LANG': 'en_US.UTF-8', 01:37:07 INFO - 'LANGUAGE': 'en_US:en', 01:37:07 INFO - 'LOGNAME': 'cltbld', 01:37:07 INFO - 'MAIL': '/var/mail/cltbld', 01:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:07 INFO - 'MOZ_NO_REMOTE': '1', 01:37:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:07 INFO - 'NO_EM_RESTART': '1', 01:37:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:07 INFO - 'PWD': '/builds/slave/test', 01:37:07 INFO - 'SHELL': '/bin/bash', 01:37:07 INFO - 'SHLVL': '1', 01:37:07 INFO - 'TERM': 'linux', 01:37:07 INFO - 'TMOUT': '86400', 01:37:07 INFO - 'USER': 'cltbld', 01:37:07 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:07 INFO - '_': '/tools/buildbot/bin/python'} 01:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:37:08 INFO - Cleaning up... 01:37:08 INFO - Return code: 0 01:37:08 INFO - Running post-action listener: _resource_record_post_action 01:37:08 INFO - Running post-action listener: _start_resource_monitoring 01:37:08 INFO - Starting resource monitoring. 01:37:08 INFO - [mozharness: 2017-04-23 08:37:08.210409Z] Finished create-virtualenv step (success) 01:37:08 INFO - [mozharness: 2017-04-23 08:37:08.211227Z] Running install step. 01:37:08 INFO - Running pre-action listener: _resource_record_pre_action 01:37:08 INFO - Running main action method: install 01:37:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:37:08 INFO - Reading from file tmpfile_stdout 01:37:08 INFO - Detecting whether we're running mozinstall >=1.0... 01:37:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:37:08 INFO - Reading from file tmpfile_stdout 01:37:08 INFO - Output received: 01:37:08 INFO - Usage: mozinstall [options] installer 01:37:08 INFO - Options: 01:37:08 INFO - -h, --help show this help message and exit 01:37:08 INFO - -d DEST, --destination=DEST 01:37:08 INFO - Directory to install application into. [default: 01:37:08 INFO - "/builds/slave/test"] 01:37:08 INFO - --app=APP Application being installed. [default: firefox] 01:37:08 INFO - mkdir: /builds/slave/test/build/application 01:37:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:37:25 INFO - Reading from file tmpfile_stdout 01:37:25 INFO - Output received: 01:37:25 INFO - /builds/slave/test/build/application/firefox/firefox 01:37:25 INFO - Running post-action listener: _resource_record_post_action 01:37:25 INFO - [mozharness: 2017-04-23 08:37:25.133194Z] Finished install step (success) 01:37:25 INFO - [mozharness: 2017-04-23 08:37:25.133467Z] Running run-tests step. 01:37:25 INFO - Running pre-action listener: _resource_record_pre_action 01:37:25 INFO - Running main action method: run_tests 01:37:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:37:25 INFO - Python 2.7.3 01:37:25 INFO - Return code: 0 01:37:25 INFO - grabbing minidump binary from tooltool 01:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2400>, '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 0x11c33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:37:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:37:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:37:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:37:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:37:25 INFO - Return code: 0 01:37:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:37:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:37:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:37:25 INFO - ENV: RUST_BACKTRACE is now 1 01:37:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:37:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:37:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 01:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 01:37:25 INFO - Using env: {'DISPLAY': ':0', 01:37:25 INFO - 'HOME': '/home/cltbld', 01:37:25 INFO - 'LANG': 'en_US.UTF-8', 01:37:25 INFO - 'LANGUAGE': 'en_US:en', 01:37:25 INFO - 'LOGNAME': 'cltbld', 01:37:25 INFO - 'MAIL': '/var/mail/cltbld', 01:37:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:37:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:25 INFO - 'MOZ_NO_REMOTE': '1', 01:37:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:37:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:25 INFO - 'NO_EM_RESTART': '1', 01:37:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:25 INFO - 'PWD': '/builds/slave/test', 01:37:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:37:25 INFO - 'RUST_BACKTRACE': '1', 01:37:25 INFO - 'SHELL': '/bin/bash', 01:37:25 INFO - 'SHLVL': '1', 01:37:25 INFO - 'TERM': 'linux', 01:37:25 INFO - 'TMOUT': '86400', 01:37:25 INFO - 'USER': 'cltbld', 01:37:25 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985', 01:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:25 INFO - '_': '/tools/buildbot/bin/python'} 01:37:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 01:37:25 INFO - mozversion application_buildid: 20170423074810 01:37:25 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:37:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:37:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:37:25 INFO - mozversion application_name: Firefox 01:37:25 INFO - mozversion application_remotingname: firefox-dev 01:37:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:37:25 INFO - mozversion application_vendor: Mozilla 01:37:25 INFO - mozversion application_version: 54.0a2 01:37:25 INFO - mozversion platform_buildid: 20170423074810 01:37:25 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:37:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:37:25 INFO - mozversion platform_version: 54.0a2 01:37:25 INFO - using testdate: 1492936645 01:37:25 INFO - actual date: 1492936645 01:37:25 INFO - starting webserver on 'localhost:60542' 01:37:25 INFO - SUITE-START | Running 2 tests 01:37:25 INFO - TEST-START | dromaeo_css 01:37:25 INFO - operating with platform_type : linux_ 01:37:25 INFO - Initialising browser for dromaeo_css test... 01:37:25 INFO - TEST-INFO | started process 3383 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7fT6lp/profile http://localhost:60542/getInfo.html) 01:37:30 INFO - PROCESS | 3383 | __metrics Screen width/height:1600/1200 01:37:30 INFO - PROCESS | 3383 | colorDepth:24 01:37:30 INFO - PROCESS | 3383 | Browser inner width/height: 1024/697 01:37:30 INFO - PROCESS | 3383 | __metrics 01:37:31 INFO - PROCESS | 3383 | 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" 01:37:31 INFO - PROCESS | 3383 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:37:32 INFO - TEST-INFO | 3383: exit 0 01:37:32 INFO - Browser initialized. 01:37:32 INFO - Running cycle 1/1 for dromaeo_css test... 01:37:32 INFO - TEST-INFO | started process 3475 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7fT6lp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:37:44 INFO - PROCESS | 3475 | RSS: Main: 139943936 01:37:44 INFO - PROCESS | 3475 | 01:40:46 INFO - PROCESS | 3475 | Cycle 1(1): loaded http://localhost:60542/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60542/tests/dromaeo/cssquery-ext.html) 01:40:47 INFO - PROCESS | 3475 | RSS: Main: 277245952 01:40:47 INFO - PROCESS | 3475 | 01:42:35 INFO - PROCESS | 3475 | ************************* 01:42:35 INFO - PROCESS | 3475 | A coding exception was thrown and uncaught in a Task. 01:42:35 INFO - PROCESS | 3475 | 01:42:35 ERROR - PROCESS | 3475 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:35 INFO - PROCESS | 3475 | Full stack: 01:42:35 INFO - PROCESS | 3475 | ************************* 01:42:35 INFO - PROCESS | 3475 | ************************* 01:42:35 INFO - PROCESS | 3475 | A coding exception was thrown and uncaught in a Task. 01:42:35 INFO - PROCESS | 3475 | 01:42:35 ERROR - PROCESS | 3475 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:35 INFO - PROCESS | 3475 | Full stack: 01:42:35 INFO - PROCESS | 3475 | ************************* 01:42:35 INFO - PROCESS | 3475 | ************************* 01:42:35 INFO - PROCESS | 3475 | A coding exception was thrown and uncaught in a Task. 01:42:35 INFO - PROCESS | 3475 | 01:42:35 ERROR - PROCESS | 3475 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:35 INFO - PROCESS | 3475 | Full stack: 01:42:35 INFO - PROCESS | 3475 | ************************* 01:42:35 INFO - PROCESS | 3475 | ************************* 01:42:35 INFO - PROCESS | 3475 | A coding exception was thrown and uncaught in a Task. 01:42:35 INFO - PROCESS | 3475 | 01:42:35 ERROR - PROCESS | 3475 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:35 INFO - PROCESS | 3475 | Full stack: 01:42:35 INFO - PROCESS | 3475 | ************************* 01:43:49 INFO - PROCESS | 3475 | Cycle 1(1): loaded http://localhost:60542/tests/dromaeo/cssquery-ext.html (next: http://localhost:60542/tests/dromaeo/cssquery-jquery.html) 01:43:50 INFO - PROCESS | 3475 | RSS: Main: 218787840 01:43:50 INFO - PROCESS | 3475 | 01:46:52 INFO - PROCESS | 3475 | Cycle 1(1): loaded http://localhost:60542/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60542/tests/dromaeo/cssquery-mootools.html) 01:46:52 INFO - PROCESS | 3475 | RSS: Main: 172163072 01:46:52 INFO - PROCESS | 3475 | 01:49:54 INFO - PROCESS | 3475 | Cycle 1(1): loaded http://localhost:60542/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60542/tests/dromaeo/cssquery-prototype.html) 01:49:55 INFO - PROCESS | 3475 | RSS: Main: 170381312 01:49:55 INFO - PROCESS | 3475 | 01:52:58 INFO - PROCESS | 3475 | Cycle 1(1): loaded http://localhost:60542/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60542/tests/dromaeo/cssquery-yui.html) 01:52:58 INFO - PROCESS | 3475 | RSS: Main: 177217536 01:52:58 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | Cycle 1(1): loaded http://localhost:60542/tests/dromaeo/cssquery-yui.html (next: http://localhost:60542/tests/dromaeo/cssquery-dojo.html) 01:56:01 INFO - PROCESS | 3475 | RSS: Main: 259878912 01:56:01 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | __start_tp_report 01:56:01 INFO - PROCESS | 3475 | _x_x_mozilla_page_load 01:56:01 INFO - PROCESS | 3475 | _x_x_mozilla_page_load_details 01:56:01 INFO - PROCESS | 3475 | |i|pagename|runs| 01:56:01 INFO - PROCESS | 3475 | |0;dojo.html;3454.44728843385;3480.982595087026;3482.233908540121;3495.2135769451893;3530.6822385985256;5764.394738552449;5844.269466316705;5856.47291743743;5857.6485410875375;5858.384869588695;5885.911711324334;5930.762769489226;5940.940590594093;5947.464728174464;5953.434423729749;5200.973995130029;5203.635745497817;5243.973780131094;5255.395629502618;5333.973330133344;5095.286659867622;5115.897682046357;5166.121759300909;5321.175217841241;5337.973310133463;5250.4487028861995;5286.154215325549;5294.179402192647;5299.28459657947;5320.388155362144;5325.334333208349;5330.493603107699;5336.06618841705;5336.226247194174;5350.5987050971335;5038;5042.4201216860165;5153.587712982952;5240.082985477564;5303.761330740126;5778.451047150514;5822.097574875901;5830.30036395631;5866.9706651466895;5884.882302353972;6259.217597800275;6276.165207305614;6289.528285378615;6305.4009869062365;6308.243010838729;6065.090236464522;6341.2707538633185;6345.397187267202;6357.459615932651;6362.1093046973465;5747.195392645033;5816.88366232673;5871.236739223917;5872.031114865999;5878.617889837147;6425.550211485244;6465.224173099258;6470.4823614110755;6471.417572418505;6475.028745688184;6170.10533472653;6232.0963460298;6233.3143354231;6246.4378205960775;6247;6056.909146362808;6083.452489275986;6132.122542660378;6165.969170154211;6169.321374648785;6220.377962203744;6256.74973001084;6258.968705156445;6260.7495700171485;6262.968685156545;6035.486983606354;6056.697165141725;6057.273127224673;6120.751898419022;6154.5384096192965;4726.763661816895;4754.762261886961;4776.761161941889;4799.904001920012;4813.4945830687975;4164.06308580573;4172.979135104305;4254.29804082323;4277.208716387416;4281.165172791338;5981.252343457068;6012.338642749294;6059.030555111161;6095.116208149882;6109.969450152721;6306.369363063749;6348.873022539616;6351.872962540816;6356.046593010993;6356.618602883842;5867.589268751147;6062.484688801413;6078.635281883101;6079.665618390943;6080.483158931452;5247.790088396498;5254.474552544715;5259.105951988171;5336.573074154058;5352.812651557177;6511.902321465182;6519.282878883224;6521.543491955613;6523.217213934358;6528.053432252392;6237.4698150657705;6244.406781355764;6280.937190628218;6292.433680968735;6293;6268.27914789805;6291.622502649856;6451.806445806634;6455.354464553507;6527.608343499405;6249.750009999549;6252.280987686474;6259.6870156492905;6263.123162757127;6274.215723034621;6368.4586810121655;6374.362563743589;6374.649394283359;6376.202974628171;6380.617162970051;5950.613210141327;5992.280926288873;6121.265448146251;6128.816135515942;6211.65835879031;6161.137440758208;6218.502519798315;6225.097360882556;6231.844203894939;6231.906521402092;6133.601315914451;6165.907511387422;6167.753289868355;6173.32093469709;6193;5942.702864856827;5971.193888824953;6015.368386319374;6130.816075517741;6140.785072522441;6079.270487541523;6081.361457046946;6087.878242435215;6097.298810636834;6114.602550834182 01:56:01 INFO - PROCESS | 3475 | |1;ext.html;767.535640937233;832.4048305461594;842.5744998775617;917.1241464401493;930.5393830054124;29995.400091998148;42354;42483.36274955878;42496;42603.57396426035;43840.561594384046;43864.3420348695;43992.56007439925;44039;44118.11763764731;7656.961715191416;7868.881966770503;9107.225885799715;9240.075992400773;9240.815183696322;10258.333208341433;10738.248322617419;10742.677719668383;10743.247972641919;10746.73133171673;10938.453077346141;10967;10968.122550196005;10980.286281391684;10984.340939543614;10830.40432776197;10877.67366978992;10914.50884710182;10926.399048052353;10927.016568508872;10429.9478502607;10477.371357718484;10495.895041049644;10496.317739346994;10497.370157790481;40890.182196355905;40994.590054099375;41020.58979410197;41050.1789964202;41344.37983430251;4209.074003719177;4240.321548552248;4280.700350975402;4371.125774845043;4382.035952090535;29099.56350654742;29360.41279174426;29461.85269073641;29508;30944.226394340098;3641.581218159919;3751.362268414376;3762.49206357141;3781.7920014399197;3829.617038296176;228393;228861;229195;230100.84949575312;231410;51844.481555183964;51867.48132518702;51869;51915.740421298404;51969;54038.45961540413;54203;54234.45765542294;54266.45733542614;54279.45720542823;16498.42255521075;16754.58113547171;16773.83226167747;16777.748333775;16789.16054197285;13177.341132943315;13370.532031378854;14229;14813.777793333109;14982.550523484313;13686;13802;13862.93068534651;13911.930440347936;14019.369128389248;5581.609287349927;5626.718664066781;5656.547476201894;5680.11958146488;5693.430656934273;998.2463240253562;1003.3277703938379;1006.225206590921;1014.5637375928421;1014.8832884218261;28008;28016.29959251035;28180.43639127206;28453.715462845106;28530.85734571314;1160.93614851184;1172.9824052639217;1187.893089621938;1208.099965525689;1208.1301462946665;14356.928215358856;14513.201773902325;14535.563933081812;14574.708505829823;14600;307050;307066;307081;307295;307648;47551.762241188575;47555;47634;47678.5232147681;47771;1417.6314158318912;1435.7343891380128;1446.1178681004578;1446.5009571697747;1450.0864455393034;24402.26793196207;25114.874425627753;25460.108896188543;25945.610815838154;26047.218583442525;35565.64434355623;35577;35589.82205088958;35675;35700;275451;277929;279712;280483;281244;24650.753492464846;24823.50352992966;27744.44511109807;27801.860990694917;28351.858240708665;37123.07192320214;37485.81257093697;37549.62450375461;38198.2360352797;38520.2295954085;30471.276952072465;30634;31066.223344416572;31280.530792038593;31342.529862052543;31042.379152417278;31211.843940780152;31401.842990785815;31761.20596985094;32007.199820004687;35642.46536302009;35660.821695891354;35732.82133589315;36857;36899.07752306214;29633.40733185281;29855.850720746257;30117.247068823453;30262;30410.695893041673;23769.16807911715;24834.00663973421;24922.252332430056;25032.2464528898;25367.112151074623 01:56:01 INFO - PROCESS | 3475 | |2;jquery.html;440.43624161073797;460.1073916601794;460.5676346562191;474.94300683917925;490.2768416585536;19724;20798.89600551999;21036.57926841462;21378.89310553445;21475.570488590238;22340.776592234073;22420.77579224207;22619.98210080546;22786.772132278715;22816.31551053467;6539.967300163493;6540.705668244928;6805.489588280874;6806.7957961261245;6868.484863635223;6702.363275488845;6812.489063320246;6814.35263649955;6815.079964204819;7562.205968373324;7851.607419629025;7887.881681774749;8036.316913062396;8051.2351326623875;8058.798530036737;7824.452288339819;7976.641051152712;8029.116797152308;8054.637541310655;8057.59712014397;7607.163212046726;7857.921420785833;7866.370690344816;7920.841583168304;7935.166807485246;28978.855105724335;29198;29498;29611.259718506994;29691.851540742373;3181.618205815317;3186.601674790651;3367.461206206995;3568.9107772305642;3593.8077772558718;17195.226214820363;17531.73702394465;17664.381746638806;18374.816251837445;18863.339783107524;2280.669302951063;2387.188355958965;2393.4734358441115;2439.524292762912;2440.5240978009297;115158.42420787926;119514.4024279873;119711.99999999913;119976;120442.39778801138;21942.341729748132;22138.667919981213;22209.44476388071;22661.28064632222;22989.080436782675;43341.133177336276;43412;43598.782006089765;43642.12715745667;44116.77941610271;9966.700998970042;10346.53440595175;10494.370337779757;10554.366737995591;10784.568617255221;8884.555772211364;8894.555272236492;8901.599428025755;9101.590428430736;9105.226055785333;9081.182693557612;9482.762930926781;9543.761405964906;9609.711708648752;9686.612535498642;3475.117459968748;3690.760100593453;3770.3967365221433;3775.565809931838;3789.696824254053;972.2416515118077;974.5273542331963;980.0983095552109;984.7095106943469;997.870276864003;20992.160313587287;21174;21242.893785530974;21309.573808524052;21340.893295533733;985.1724551376734;992.8014397120605;999.9950000250097;1011.1001208924064;1014.2291858187683;10694.679159625224;11521.423928803526;11630.53477860876;11662.650120496399;11750.823737643943;646585;647283;650974;659949;662703;104408;107775;112456.43771781088;113190.43404782923;113924;1296.1510210811932;1298.0913360647585;1332.9066965312336;1337.518493342396;1341.4097796969302;17844.19701113479;18146.364877229233;18407;18488.82265275708;18661.90669046646;48388.27417588668;48514.51485485241;48901.2664810021;48902.26646600375;49486;66260.66869665813;66380;66968.33031669816;67725.66137169283;68895.65552172408;9363.953180234055;11119.610813621484;11759.176857620227;12375.62873113808;12544.87455125437;12958;13182.868171318163;13452.93273533626;13508.797368039684;13527.932360338134;11934.940325298317;12020.098492613264;12078.879211207775;12163.026957843529;12488.687782805502;11174.050205732603;11209.271397359145;11375.829362559734;11390.316581005167;11591.072714183014;12071.698207544881;12192;12310.507579696712;12364.072694548126;12569.245845249314;11297.604583839855;11983.400829958642;12087.456064477296;12105;12253.142280040312;12503.124781265224;12522.561710340458;12533.185342952678;12555.183913045623;12578.811317830423 01:56:01 INFO - PROCESS | 3475 | |3;mootools.html;1450.5068276785903;1539.122700060965;1559.91420471874;1579.6603730198008;1596.1939220693555;9601.183899368554;11295.435228238586;11514.424278786058;11961.760764784722;11982.460789264482;11736.941315293412;11815.7636847263;11816.172867899251;11844.466998985044;11850.940745296262;2065.5249292662697;2141.0579345088113;2472.295395812194;2520.5085008423366;2524.7601477859666;2470.3700556358194;2566.538023155831;2579.432524844531;2588.0812311629384;2594.247668176227;2480.1815400917653;2496.588062969608;2506.348349429152;2509.8494090354548;2513.0576033987254;2438.67077944477;2445.0342114864707;2457.5207834472285;2465.359006658273;2473.4434752180796;2338.5089131282452;2344.2732752846673;2347.730011048734;2348.330725743155;2365.1485465232504;11693.298402095901;11941.10441716866;12006.759864802654;12024.158308918379;12029.819552706716;5933.762649494015;6044.818655440344;6073.028315469548;6101.72542235596;6102.755889764404;3282.720968717662;3299.15871452778;3344.749143814199;3587.2287458196615;3650.324689932371;1277.3613193403298;1293.1465232946211;1326.9469221231138;1328.2694518015053;1338.3910320804011;17727.468175954742;17829;17867.017314047724;17883.28466861324;17886.821131788645;4905.950940490549;5101.801076746961;5128.717920514333;5148.510891465305;5149.6910185389;14708.852911470962;14764.261786910827;14782.926085369505;14784.186869722053;14785.852141478663;6165.660888651168;6170.043643235216;6177.289611694712;6181.227346581677;6185.165002724664;4647.07058588277;4788.928166077442;5345.118055520873;5416.404195538487;5491.203775452537;4328.567143285709;4340.65274778017;4365.738055716667;4421.204183247047;4428.180786554433;1183.976320473603;1207.0464333176867;1221.84116064911;1222.5048855213652;1227.2084505494086;966.569876404993;973.2360097323567;973.6543527047796;980;986.8174387738147;6694.832629184212;6725.293844146392;6782.491313151533;6809.250982391933;6959.338862808025;1035;1055.3087727538475;1062.1396668698378;1072.5602502973743;1076.9407682577378;4315;4413.8234470621455;4414.713043652152;4425.889352766142;4451.554844515523;14200.28998550089;14687.26563671812;14788;14805.03767255125;14807.48173813933;13375.531856385174;13857.4457021718;14425.639359016108;14463;14468.131912085308;10599.62901298451;10790.08284295828;10801.081908037904;10802.135829133807;10816.134709223086;6962.303769622998;7155.856882862209;7167.641617918979;7191.137063552407;7217.278272172741;13009;13155.934220328838;13315.467381305027;13411.329433528479;13486.05597608158;13349.799753003519;13503.527376542166;13623.931880340535;13681.521146759816;13741.450341986209;8462.534560599226;8644.438111522732;9548.665796697082;9998.650047248311;10255.948720256649;11016.17378696585;11165.944170279097;11228;11255.943720281346;11262.155338349492;9207.76980575491;9300.953495232481;9322.720318390458;9410.670626528039;9510.904890951279;8946.194842463949;9056.909430905871;9069.591868366071;9118;9191.77020574491;8568.35737319691;8706.47761134334;8765.824683506422;8773.38586298953;8804.559772011502;6152.846178845564;6183.598066125687;6238.937610623836;6288.339724328879;6438.420542151229;5975.43233392827;6057.242844644419;6062.242219722535;6113.938860611515;6161.144540710451 01:56:01 INFO - PROCESS | 3475 | |4;prototype.html;412.9793510324483;421.38267438203025;435.4339358833516;437.1628331744709;441.9999999999998;5233.528982391584;5302.575793936486;5364.651297665659;5379.354477462705;5410.296661434018;5214.478552144793;5227.817026404067;5238.297553295185;5257.05488969081;5273.393559740631;4262.1688770689725;4433.600975912167;4477.686561940665;4486.327050942352;4513.932291015637;4453.77521181676;4527.818887244506;4527.909441811162;4540.4778450478125;4564.5435456454425;4498.550144985508;4521.321801729734;4524.298733696282;4528.547145285478;4562.361269422284;4312.951348109638;4442.844500442469;4476.037651904856;4494.325851122325;4523.2762757958735;4318.460192475941;4324.329728892027;4362.516744306951;4391.648668106575;4427.579379958899;5240.6069544784295;5246.055709972204;5254.868628284324;5272.182811664198;5279.973600132013;5228.529432351107;5277.419483856811;5316.601254905898;5343.091674415358;5356.625036247464;5498.257735205736;5532.585056120807;5548.6393384430285;5576.275084239063;5618.7190640468225;4940.48124946879;4940.555350018516;4968.826091086795;5000.0999820032375;5000.749962501896;5533.6403133796175;5610;5674.574406919456;5856.941430585683;5880.441358070976;4949.826756063592;5059.494050594984;5072.9746351268;5087.771050302746;5097.643164978416;5175.223716442489;5187.429382767892;5192.221166825007;5193.246979187997;5253.789848406097;5048.116579598629;5257.767497164743;5313.840584782385;5373.516383525502;5391.81128660495;5555.444455554412;5589.636673616202;5608.887822243614;5611.717145469865;5625.831225063254;1791.5162906015303;1796.7035439152394;1799.9560255052038;1806.1872157529165;1806.449033044934;1427.9214643194723;1462.6197188730794;1468.1411374345914;1488.5906375746756;1504.2704288420107;5550.4467944745065;5565.805196818092;5573.944260557424;5575.693336866511;5579.972100139474;5624.353199382042;5631;5665.886682266331;5687.829365119053;5736.799212027559;5260.842174734764;5401.405115251014;5404.621676482687;5492.450754924475;5520.702634880799;2937.985310073436;2971.202422534379;2984.4478771427357;2995.9400811983637;3001.5347621118763;5900.940990590039;5905.350411454737;5914.615549989237;5928.555358348055;5977.551683623746;5525.585581081355;5766.538676905838;5781.71091445426;5797.449242321973;5810.564207684441;5861.267341582302;5886.823395298148;5903.498202652822;5906.468417842415;5912.260967379078;5401.891962160814;5618.6628802272;5640.435956404327;5710.4004079572705;5712.914306285492;5394.57444522723;5615.8876822464135;5625.746841392065;5631.887362252814;5652.417323149525;5726.656400615976;5783.681897495678;5912.763489460545;5915.260592425947;5919.845630102089;4693.460252071056;4733.656839864289;4763.880902977453;4766.523347665345;4767.713937163781;5414;5613.578981576316;5633.2395126657375;5735.397783232867;5739.196512488338;5431.185322201702;5484.287042684426;5517.751701173376;5523.8066667666435;5535.951697871813;5462;5581.330240371181;5598.580106492111;5611.186377975089;5623.891040859678;5358.437364076716;5420.891582168413;5436.157395603688;5442.700651464207;5467.562594992471;5475.09660905946;5510.476504732044;5549.556035517229;5566.192902029264;5575.275214222125;5196.844094677166;5417.918731218956;5453.818472610988;5483.341998959991;5546.362168350692 01:56:01 INFO - PROCESS | 3475 | |5;yui.html;846.7078857794058;873.1399571422152;878.2710350409156;894.8881389826272;933.822573710995;17252.74120888186;17588.296468141274;17868.195931183094;17926.82073179268;18591;17849;18735.15691793869;18773;18871.433856984313;18913;863.568215892054;880.7049638371153;883.9116088391157;896.3232759266745;899.4917871402637;886.4149661223594;888.4979986307748;888.6578667213105;888.8044630181349;889.6841621224469;925.9074092590721;928.767808047988;928.9966835817335;929.7675581104724;930.5951910918739;922.3267015078962;923.7921467669788;925.0934084597133;925.5742358515091;926.2312280806948;674.2818897873749;674.9730010799562;675.3988949834651;682.2018238660779;682.3142741544765;17916.641667166583;18051.548711282325;18080.547986300448;18506;18693.15880785368;17331.266873312696;17466.301347946068;17643.73534396985;17724.822751772575;17728.29086836525;13872.791908121386;13983.23092229927;13989.510367137204;13996.650083747885;14059.437622495088;4117.8970525736795;4127.484064491939;4143.4199212110325;4207.242696314662;4266.466691663542;50119.49880501221;55104.17343739847;80326;91806.99999999933;92151;13755.931220343835;13791.241481718402;13826.239556824472;14044.227567483886;14060.296985150906;25685.74314256871;25723.742762572136;25730;26093;26097.608535871976;9016.143466370684;9085.13691199335;9189.77025574366;9305.674301399418;9366.344355895022;4974.602031837517;5076.974615126975;5150.253213284052;5178.300929374562;5182.8963420732125;3735.7384983050924;3827.004978705557;3830.3105441020884;3830.3296923038356;3830.616938306147;637.6269882119012;637.7500099804342;645.5255387290432;652.8400541867273;654.1104098426136;848.7708318753902;854.5171977832505;866.1252135343349;879.2482427524502;880.1459948384663;10896.891031089746;11734.354610496333;11756.23584467007;12181.08641851847;12361.93819030899;855.8545047341843;861.9138086191456;875.2472873328932;882.7969566999626;894.1282249806517;5716.101295232617;5805.603721822697;5813.476787089182;5870.618409803349;5926.614770039933;82103;82587.58706206431;82979;83423;86041.13958860456;59230.11154832681;65437;65473.67263163749;69662;73054.26945730581;60049.39950600438;67894.32105678979;71680.2831971684;73704.63147684227;76363.23636763672;11115.44422778874;12240;12240.204386714835;12449.626511204679;12688.429020694275;45698.77150614226;46710.06579868316;46744.53255467538;46936.53063469322;47189.764051179525;55355;55916;56421;56453;56696.71651641715;14539.92730036343;14836.62908427298;14886.37326593986;15045.774313385527;15091;17039.914800426413;17046.744298835278;17088.487345379657;17093.914530427268;17252.223649936033;14847.183404912834;15248.161351125795;15459.536213913601;15498.5350439487;15588.92205538965;15021.533673312479;15522.84477155214;15579.766303505683;15850.762238566662;16116.758248626045;16203.351865925231;16514.42199523059;16548.669026619642;17176.82823171752;17324;11604.593839215924;12093.213941093802;12423.254604723745;12489.18820276679;12570.371481426075;13337.53318633843;13344;13448.327583620585;13469.528566500518;13674.179549227078 01:56:01 INFO - PROCESS | 3475 | __end_tp_report 01:56:01 INFO - PROCESS | 3475 | __start_cc_report 01:56:01 INFO - PROCESS | 3475 | _x_x_mozilla_cycle_collect,1364 01:56:01 INFO - PROCESS | 3475 | __end_cc_report 01:56:01 INFO - PROCESS | 3475 | __startTimestamp1492937761709__endTimestamp 01:56:01 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | ------- Summary: start ------- 01:56:01 INFO - PROCESS | 3475 | Number of tests: 6 01:56:01 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | [#0] dojo.html Cycles:165 Average:5819.27 Median:6079.67 stddev:672.65 (11.1%) stddev-sans-first:648.63 01:56:01 INFO - PROCESS | 3475 | Values: 3454.4 3481.0 3482.2 3495.2 3530.7 5764.4 5844.3 5856.5 5857.6 5858.4 5885.9 5930.8 5940.9 5947.5 5953.4 5201.0 5203.6 5244.0 5255.4 5334.0 5095.3 5115.9 5166.1 5321.2 5338.0 5250.4 5286.2 5294.2 5299.3 5320.4 5325.3 5330.5 5336.1 5336.2 5350.6 5038.0 5042.4 5153.6 5240.1 5303.8 5778.5 5822.1 5830.3 5867.0 5884.9 6259.2 6276.2 6289.5 6305.4 6308.2 6065.1 6341.3 6345.4 6357.5 6362.1 5747.2 5816.9 5871.2 5872.0 5878.6 6425.6 6465.2 6470.5 6471.4 6475.0 6170.1 6232.1 6233.3 6246.4 6247.0 6056.9 6083.5 6132.1 6166.0 6169.3 6220.4 6256.7 6259.0 6260.7 6263.0 6035.5 6056.7 6057.3 6120.8 6154.5 4726.8 4754.8 4776.8 4799.9 4813.5 4164.1 4173.0 4254.3 4277.2 4281.2 5981.3 6012.3 6059.0 6095.1 6110.0 6306.4 6348.9 6351.9 6356.0 6356.6 5867.6 6062.5 6078.6 6079.7 6080.5 5247.8 5254.5 5259.1 5336.6 5352.8 6511.9 6519.3 6521.5 6523.2 6528.1 6237.5 6244.4 6280.9 6292.4 6293.0 6268.3 6291.6 6451.8 6455.4 6527.6 6249.8 6252.3 6259.7 6263.1 6274.2 6368.5 6374.4 6374.6 6376.2 6380.6 5950.6 5992.3 6121.3 6128.8 6211.7 6161.1 6218.5 6225.1 6231.8 6231.9 6133.6 6165.9 6167.8 6173.3 6193.0 5942.7 5971.2 6015.4 6130.8 6140.8 6079.3 6081.4 6087.9 6097.3 6114.6 01:56:01 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | [#1] ext.html Cycles:180 Average:43343.46 Median:30056.32 stddev:71386.57 (237.5%) stddev-sans-first:71515.25 01:56:01 INFO - PROCESS | 3475 | Values: 767.5 832.4 842.6 917.1 930.5 29995.4 42354.0 42483.4 42496.0 42603.6 43840.6 43864.3 43992.6 44039.0 44118.1 7657.0 7868.9 9107.2 9240.1 9240.8 10258.3 10738.2 10742.7 10743.2 10746.7 10938.5 10967.0 10968.1 10980.3 10984.3 10830.4 10877.7 10914.5 10926.4 10927.0 10429.9 10477.4 10495.9 10496.3 10497.4 40890.2 40994.6 41020.6 41050.2 41344.4 4209.1 4240.3 4280.7 4371.1 4382.0 29099.6 29360.4 29461.9 29508.0 30944.2 3641.6 3751.4 3762.5 3781.8 3829.6 228393.0 228861.0 229195.0 230100.8 231410.0 51844.5 51867.5 51869.0 51915.7 51969.0 54038.5 54203.0 54234.5 54266.5 54279.5 16498.4 16754.6 16773.8 16777.7 16789.2 13177.3 13370.5 14229.0 14813.8 14982.6 13686.0 13802.0 13862.9 13911.9 14019.4 5581.6 5626.7 5656.5 5680.1 5693.4 998.2 1003.3 1006.2 1014.6 1014.9 28008.0 28016.3 28180.4 28453.7 28530.9 1160.9 1173.0 1187.9 1208.1 1208.1 14356.9 14513.2 14535.6 14574.7 14600.0 307050.0 307066.0 307081.0 307295.0 307648.0 47551.8 47555.0 47634.0 47678.5 47771.0 1417.6 1435.7 1446.1 1446.5 1450.1 24402.3 25114.9 25460.1 25945.6 26047.2 35565.6 35577.0 35589.8 35675.0 35700.0 275451.0 277929.0 279712.0 280483.0 281244.0 24650.8 24823.5 27744.4 27801.9 28351.9 37123.1 37485.8 37549.6 38198.2 38520.2 30471.3 30634.0 31066.2 31280.5 31342.5 31042.4 31211.8 31401.8 31761.2 32007.2 35642.5 35660.8 35732.8 36857.0 36899.1 29633.4 29855.9 30117.2 30262.0 30410.7 23769.2 24834.0 24922.3 25032.2 25367.1 01:56:01 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | [#2] jquery.html Cycles:180 Average:38091.41 Median:12688.13 stddev:107694.49 (848.8%) stddev-sans-first:107959.49 01:56:01 INFO - PROCESS | 3475 | Values: 440.4 460.1 460.6 474.9 490.3 19724.0 20798.9 21036.6 21378.9 21475.6 22340.8 22420.8 22620.0 22786.8 22816.3 6540.0 6540.7 6805.5 6806.8 6868.5 6702.4 6812.5 6814.4 6815.1 7562.2 7851.6 7887.9 8036.3 8051.2 8058.8 7824.5 7976.6 8029.1 8054.6 8057.6 7607.2 7857.9 7866.4 7920.8 7935.2 28978.9 29198.0 29498.0 29611.3 29691.9 3181.6 3186.6 3367.5 3568.9 3593.8 17195.2 17531.7 17664.4 18374.8 18863.3 2280.7 2387.2 2393.5 2439.5 2440.5 115158.4 119514.4 119712.0 119976.0 120442.4 21942.3 22138.7 22209.4 22661.3 22989.1 43341.1 43412.0 43598.8 43642.1 44116.8 9966.7 10346.5 10494.4 10554.4 10784.6 8884.6 8894.6 8901.6 9101.6 9105.2 9081.2 9482.8 9543.8 9609.7 9686.6 3475.1 3690.8 3770.4 3775.6 3789.7 972.2 974.5 980.1 984.7 997.9 20992.2 21174.0 21242.9 21309.6 21340.9 985.2 992.8 1000.0 1011.1 1014.2 10694.7 11521.4 11630.5 11662.7 11750.8 646585.0 647283.0 650974.0 659949.0 662703.0 104408.0 107775.0 112456.4 113190.4 113924.0 1296.2 1298.1 1332.9 1337.5 1341.4 17844.2 18146.4 18407.0 18488.8 18661.9 48388.3 48514.5 48901.3 48902.3 49486.0 66260.7 66380.0 66968.3 67725.7 68895.7 9364.0 11119.6 11759.2 12375.6 12544.9 12958.0 13182.9 13452.9 13508.8 13527.9 11934.9 12020.1 12078.9 12163.0 12488.7 11174.1 11209.3 11375.8 11390.3 11591.1 12071.7 12192.0 12310.5 12364.1 12569.2 11297.6 11983.4 12087.5 12105.0 12253.1 12503.1 12522.6 12533.2 12555.2 12578.8 01:56:01 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | [#3] mootools.html Cycles:180 Average:7249.69 Median:2501.47 stddev:4706.25 (188.1%) stddev-sans-first:4699.28 01:56:01 INFO - PROCESS | 3475 | Values: 1450.5 1539.1 1559.9 1579.7 1596.2 9601.2 11295.4 11514.4 11961.8 11982.5 11736.9 11815.8 11816.2 11844.5 11850.9 2065.5 2141.1 2472.3 2520.5 2524.8 2470.4 2566.5 2579.4 2588.1 2594.2 2480.2 2496.6 2506.3 2509.8 2513.1 2438.7 2445.0 2457.5 2465.4 2473.4 2338.5 2344.3 2347.7 2348.3 2365.1 11693.3 11941.1 12006.8 12024.2 12029.8 5933.8 6044.8 6073.0 6101.7 6102.8 3282.7 3299.2 3344.7 3587.2 3650.3 1277.4 1293.1 1326.9 1328.3 1338.4 17727.5 17829.0 17867.0 17883.3 17886.8 4906.0 5101.8 5128.7 5148.5 5149.7 14708.9 14764.3 14782.9 14784.2 14785.9 6165.7 6170.0 6177.3 6181.2 6185.2 4647.1 4788.9 5345.1 5416.4 5491.2 4328.6 4340.7 4365.7 4421.2 4428.2 1184.0 1207.0 1221.8 1222.5 1227.2 966.6 973.2 973.7 980.0 986.8 6694.8 6725.3 6782.5 6809.3 6959.3 1035.0 1055.3 1062.1 1072.6 1076.9 4315.0 4413.8 4414.7 4425.9 4451.6 14200.3 14687.3 14788.0 14805.0 14807.5 13375.5 13857.4 14425.6 14463.0 14468.1 10599.6 10790.1 10801.1 10802.1 10816.1 6962.3 7155.9 7167.6 7191.1 7217.3 13009.0 13155.9 13315.5 13411.3 13486.1 13349.8 13503.5 13623.9 13681.5 13741.5 8462.5 8644.4 9548.7 9998.7 10255.9 11016.2 11165.9 11228.0 11255.9 11262.2 9207.8 9301.0 9322.7 9410.7 9510.9 8946.2 9056.9 9069.6 9118.0 9191.8 8568.4 8706.5 8765.8 8773.4 8804.6 6152.8 6183.6 6238.9 6288.3 6438.4 5975.4 6057.2 6062.2 6113.9 6161.1 01:56:01 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | [#4] prototype.html Cycles:180 Average:4904.89 Median:5385.58 stddev:1260.40 (23.4%) stddev-sans-first:1218.01 01:56:01 INFO - PROCESS | 3475 | Values: 413.0 421.4 435.4 437.2 442.0 5233.5 5302.6 5364.7 5379.4 5410.3 5214.5 5227.8 5238.3 5257.1 5273.4 4262.2 4433.6 4477.7 4486.3 4513.9 4453.8 4527.8 4527.9 4540.5 4564.5 4498.6 4521.3 4524.3 4528.5 4562.4 4313.0 4442.8 4476.0 4494.3 4523.3 4318.5 4324.3 4362.5 4391.6 4427.6 5240.6 5246.1 5254.9 5272.2 5280.0 5228.5 5277.4 5316.6 5343.1 5356.6 5498.3 5532.6 5548.6 5576.3 5618.7 4940.5 4940.6 4968.8 5000.1 5000.7 5533.6 5610.0 5674.6 5856.9 5880.4 4949.8 5059.5 5073.0 5087.8 5097.6 5175.2 5187.4 5192.2 5193.2 5253.8 5048.1 5257.8 5313.8 5373.5 5391.8 5555.4 5589.6 5608.9 5611.7 5625.8 1791.5 1796.7 1800.0 1806.2 1806.4 1427.9 1462.6 1468.1 1488.6 1504.3 5550.4 5565.8 5573.9 5575.7 5580.0 5624.4 5631.0 5665.9 5687.8 5736.8 5260.8 5401.4 5404.6 5492.5 5520.7 2938.0 2971.2 2984.4 2995.9 3001.5 5900.9 5905.4 5914.6 5928.6 5977.6 5525.6 5766.5 5781.7 5797.4 5810.6 5861.3 5886.8 5903.5 5906.5 5912.3 5401.9 5618.7 5640.4 5710.4 5712.9 5394.6 5615.9 5625.7 5631.9 5652.4 5726.7 5783.7 5912.8 5915.3 5919.8 4693.5 4733.7 4763.9 4766.5 4767.7 5414.0 5613.6 5633.2 5735.4 5739.2 5431.2 5484.3 5517.8 5523.8 5536.0 5462.0 5581.3 5598.6 5611.2 5623.9 5358.4 5420.9 5436.2 5442.7 5467.6 5475.1 5510.5 5549.6 5566.2 5575.3 5196.8 5417.9 5453.8 5483.3 5546.4 01:56:01 INFO - PROCESS | 3475 | 01:56:01 INFO - PROCESS | 3475 | [#5] yui.html Cycles:180 Average:19102.91 Median:46204.42 stddev:22890.60 (49.5%) stddev-sans-first:22913.76 01:56:01 INFO - PROCESS | 3475 | Values: 846.7 873.1 878.3 894.9 933.8 17252.7 17588.3 17868.2 17926.8 18591.0 17849.0 18735.2 18773.0 18871.4 18913.0 863.6 880.7 883.9 896.3 899.5 886.4 888.5 888.7 888.8 889.7 925.9 928.8 929.0 929.8 930.6 922.3 923.8 925.1 925.6 926.2 674.3 675.0 675.4 682.2 682.3 17916.6 18051.5 18080.5 18506.0 18693.2 17331.3 17466.3 17643.7 17724.8 17728.3 13872.8 13983.2 13989.5 13996.7 14059.4 4117.9 4127.5 4143.4 4207.2 4266.5 50119.5 55104.2 80326.0 91807.0 92151.0 13755.9 13791.2 13826.2 14044.2 14060.3 25685.7 25723.7 25730.0 26093.0 26097.6 9016.1 9085.1 9189.8 9305.7 9366.3 4974.6 5077.0 5150.3 5178.3 5182.9 3735.7 3827.0 3830.3 3830.3 3830.6 637.6 637.8 645.5 652.8 654.1 848.8 854.5 866.1 879.2 880.1 10896.9 11734.4 11756.2 12181.1 12361.9 855.9 861.9 875.2 882.8 894.1 5716.1 5805.6 5813.5 5870.6 5926.6 82103.0 82587.6 82979.0 83423.0 86041.1 59230.1 65437.0 65473.7 69662.0 73054.3 60049.4 67894.3 71680.3 73704.6 76363.2 11115.4 12240.0 12240.2 12449.6 12688.4 45698.8 46710.1 46744.5 46936.5 47189.8 55355.0 55916.0 56421.0 56453.0 56696.7 14539.9 14836.6 14886.4 15045.8 15091.0 17039.9 17046.7 17088.5 17093.9 17252.2 14847.2 15248.2 15459.5 15498.5 15588.9 15021.5 15522.8 15579.8 15850.8 16116.8 16203.4 16514.4 16548.7 17176.8 17324.0 11604.6 12093.2 12423.3 12489.2 12570.4 13337.5 13344.0 13448.3 13469.5 13674.2 01:56:01 INFO - PROCESS | 3475 | -------- Summary: end -------- 01:56:01 INFO - PROCESS | 3475 | 01:56:02 INFO - TEST-INFO | 3475: exit 0 01:56:02 INFO - TEST-OK | dromaeo_css | took 1117022ms 01:56:02 INFO - TEST-START | kraken 01:56:02 INFO - operating with platform_type : linux_ 01:56:02 INFO - Initialising browser for kraken test... 01:56:02 INFO - TEST-INFO | started process 3800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOo0B5_/profile http://localhost:60542/getInfo.html) 01:56:06 INFO - PROCESS | 3800 | __metrics Screen width/height:1600/1200 01:56:06 INFO - PROCESS | 3800 | colorDepth:24 01:56:06 INFO - PROCESS | 3800 | Browser inner width/height: 1024/697 01:56:06 INFO - PROCESS | 3800 | __metrics 01:56:07 INFO - PROCESS | 3800 | 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" 01:56:07 INFO - PROCESS | 3800 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:56:08 INFO - TEST-INFO | 3800: exit 0 01:56:08 INFO - Browser initialized. 01:56:08 INFO - Running cycle 1/1 for kraken test... 01:56:08 INFO - TEST-INFO | started process 3894 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOo0B5_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:56:19 INFO - PROCESS | 3894 | RSS: Main: 140046336 01:56:19 INFO - PROCESS | 3894 | 01:56:22 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60542/tests/kraken/driver.html?testName=audio-beat-detection) 01:56:23 INFO - PROCESS | 3894 | RSS: Main: 183918592 01:56:23 INFO - PROCESS | 3894 | 01:56:25 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60542/tests/kraken/driver.html?testName=audio-dft) 01:56:26 INFO - PROCESS | 3894 | RSS: Main: 173846528 01:56:26 INFO - PROCESS | 3894 | 01:56:29 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60542/tests/kraken/driver.html?testName=audio-fft) 01:56:29 INFO - PROCESS | 3894 | RSS: Main: 266637312 01:56:29 INFO - PROCESS | 3894 | 01:56:31 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60542/tests/kraken/driver.html?testName=audio-oscillator) 01:56:32 INFO - PROCESS | 3894 | RSS: Main: 173019136 01:56:32 INFO - PROCESS | 3894 | 01:56:34 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60542/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:56:35 INFO - PROCESS | 3894 | RSS: Main: 175325184 01:56:35 INFO - PROCESS | 3894 | 01:56:38 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60542/tests/kraken/driver.html?testName=imaging-darkroom) 01:56:38 INFO - PROCESS | 3894 | RSS: Main: 214290432 01:56:38 INFO - PROCESS | 3894 | 01:56:42 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60542/tests/kraken/driver.html?testName=imaging-desaturate) 01:56:42 INFO - PROCESS | 3894 | RSS: Main: 212799488 01:56:42 INFO - PROCESS | 3894 | 01:56:45 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60542/tests/kraken/driver.html?testName=json-parse-financial) 01:56:46 INFO - PROCESS | 3894 | RSS: Main: 211144704 01:56:46 INFO - PROCESS | 3894 | 01:56:47 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60542/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:56:48 INFO - PROCESS | 3894 | RSS: Main: 173600768 01:56:48 INFO - PROCESS | 3894 | 01:56:49 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60542/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:56:50 INFO - PROCESS | 3894 | RSS: Main: 179613696 01:56:50 INFO - PROCESS | 3894 | 01:56:52 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60542/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:56:53 INFO - PROCESS | 3894 | RSS: Main: 190332928 01:56:53 INFO - PROCESS | 3894 | 01:56:55 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60542/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:56:56 INFO - PROCESS | 3894 | RSS: Main: 199483392 01:56:56 INFO - PROCESS | 3894 | 01:56:59 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60542/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:56:59 INFO - PROCESS | 3894 | RSS: Main: 195624960 01:56:59 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | Cycle 1(1): loaded http://localhost:60542/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60542/tests/kraken/driver.html?testName=ai-astar) 01:57:01 INFO - PROCESS | 3894 | RSS: Main: 245907456 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | __start_tp_report 01:57:01 INFO - PROCESS | 3894 | _x_x_mozilla_page_load 01:57:01 INFO - PROCESS | 3894 | _x_x_mozilla_page_load_details 01:57:01 INFO - PROCESS | 3894 | |i|pagename|runs| 01:57:01 INFO - PROCESS | 3894 | |0;ai-astar;103;114;113;114;113;112;113;113;112;114 01:57:01 INFO - PROCESS | 3894 | |1;audio-beat-detection;148;115;115;154;117;115;115;115;134;117 01:57:01 INFO - PROCESS | 3894 | |2;audio-dft;162;162;161;159;161;161;162;159;160;161 01:57:01 INFO - PROCESS | 3894 | |3;audio-fft;75;77;75;114;76;76;76;75;90;76 01:57:01 INFO - PROCESS | 3894 | |4;audio-oscillator;82;81;101;82;82;83;100;82;82;122 01:57:01 INFO - PROCESS | 3894 | |5;imaging-gaussian-blur;113;113;113;112;112;113;113;113;113;113 01:57:01 INFO - PROCESS | 3894 | |6;imaging-darkroom;147;147;147;147;148;146;146;147;146;149 01:57:01 INFO - PROCESS | 3894 | |7;imaging-desaturate;94;94;93;94;94;94;94;94;94;94 01:57:01 INFO - PROCESS | 3894 | |8;json-parse-financial;53;53;54;54;54;53;55;55;54;56 01:57:01 INFO - PROCESS | 3894 | |9;json-stringify-tinderbox;54;52;52;55;52;53;51;52;53;52 01:57:01 INFO - PROCESS | 3894 | |10;stanford-crypto-aes;84;82;76;78;75;84;79;82;82;76 01:57:01 INFO - PROCESS | 3894 | |11;stanford-crypto-ccm;123;130;122;127;120;120;118;118;163;129 01:57:01 INFO - PROCESS | 3894 | |12;stanford-crypto-pbkdf2;156;159;155;157;160;157;163;158;158;161 01:57:01 INFO - PROCESS | 3894 | |13;stanford-crypto-sha256-iterative;63;61;61;66;59;59;60;63;60;59 01:57:01 INFO - PROCESS | 3894 | __end_tp_report 01:57:01 INFO - PROCESS | 3894 | __start_cc_report 01:57:01 INFO - PROCESS | 3894 | _x_x_mozilla_cycle_collect,1685 01:57:01 INFO - PROCESS | 3894 | __end_cc_report 01:57:01 INFO - PROCESS | 3894 | __startTimestamp1492937821500__endTimestamp 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | ------- Summary: start ------- 01:57:01 INFO - PROCESS | 3894 | Number of tests: 14 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:3.28 (2.9%) stddev-sans-first:0.78 01:57:01 INFO - PROCESS | 3894 | Values: 103.0 114.0 113.0 114.0 113.0 112.0 113.0 113.0 112.0 114.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:117.00 stddev:15.19 (13.0%) stddev-sans-first:13.52 01:57:01 INFO - PROCESS | 3894 | Values: 148.0 115.0 115.0 154.0 117.0 115.0 115.0 115.0 134.0 117.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.12 01:57:01 INFO - PROCESS | 3894 | Values: 162.0 162.0 161.0 159.0 161.0 161.0 162.0 159.0 160.0 161.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:12.45 (16.4%) stddev-sans-first:13.01 01:57:01 INFO - PROCESS | 3894 | Values: 75.0 77.0 75.0 114.0 76.0 76.0 76.0 75.0 90.0 76.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:82.00 stddev:13.72 (16.7%) stddev-sans-first:14.27 01:57:01 INFO - PROCESS | 3894 | Values: 82.0 81.0 101.0 82.0 82.0 83.0 100.0 82.0 82.0 122.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 01:57:01 INFO - PROCESS | 3894 | Values: 113.0 113.0 113.0 112.0 112.0 113.0 113.0 113.0 113.0 113.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 01:57:01 INFO - PROCESS | 3894 | Values: 147.0 147.0 147.0 147.0 148.0 146.0 146.0 147.0 146.0 149.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 01:57:01 INFO - PROCESS | 3894 | Values: 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:0.97 01:57:01 INFO - PROCESS | 3894 | Values: 53.0 53.0 54.0 54.0 54.0 53.0 55.0 55.0 54.0 56.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#9] json-stringify-tinderbox Cycles:10 Average:52.60 Median:52.50 stddev:1.17 (2.2%) stddev-sans-first:1.13 01:57:01 INFO - PROCESS | 3894 | Values: 54.0 52.0 52.0 55.0 52.0 53.0 51.0 52.0 53.0 52.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:82.00 stddev:3.43 (4.2%) stddev-sans-first:3.28 01:57:01 INFO - PROCESS | 3894 | Values: 84.0 82.0 76.0 78.0 75.0 84.0 79.0 82.0 82.0 76.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:125.00 stddev:13.37 (10.7%) stddev-sans-first:14.11 01:57:01 INFO - PROCESS | 3894 | Values: 123.0 130.0 122.0 127.0 120.0 120.0 118.0 118.0 163.0 129.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.40 Median:158.50 stddev:2.41 (1.5%) stddev-sans-first:2.40 01:57:01 INFO - PROCESS | 3894 | Values: 156.0 159.0 155.0 157.0 160.0 157.0 163.0 158.0 158.0 161.0 01:57:01 INFO - PROCESS | 3894 | 01:57:01 INFO - PROCESS | 3894 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:2.28 (3.7%) stddev-sans-first:2.32 01:57:01 INFO - PROCESS | 3894 | Values: 63.0 61.0 61.0 66.0 59.0 59.0 60.0 63.0 60.0 59.0 01:57:01 INFO - PROCESS | 3894 | -------- Summary: end -------- 01:57:01 INFO - PROCESS | 3894 | 01:57:02 INFO - TEST-INFO | 3894: exit 0 01:57:02 INFO - TEST-OK | kraken | took 60084ms 01:57:02 INFO - SUITE-END | took 1177s 01:57:02 INFO - Completed test suite (00:19:37) 01:57:02 INFO - javascript benchmark 01:57:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7964.527022949855, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3454.44728843385, 3480.982595087026, 3482.233908540121, 3495.2135769451893, 3530.6822385985256, 5764.394738552449, 5844.269466316705, 5856.47291743743, 5857.6485410875375, 5858.384869588695, 5885.911711324334, 5930.762769489226, 5940.940590594093, 5947.464728174464, 5953.434423729749, 5200.973995130029, 5203.635745497817, 5243.973780131094, 5255.395629502618, 5333.973330133344, 5095.286659867622, 5115.897682046357, 5166.121759300909, 5321.175217841241, 5337.973310133463, 5250.4487028861995, 5286.154215325549, 5294.179402192647, 5299.28459657947, 5320.388155362144, 5325.334333208349, 5330.493603107699, 5336.06618841705, 5336.226247194174, 5350.5987050971335, 5038.0, 5042.4201216860165, 5153.587712982952, 5240.082985477564, 5303.761330740126, 5778.451047150514, 5822.097574875901, 5830.30036395631, 5866.9706651466895, 5884.882302353972, 6259.217597800275, 6276.165207305614, 6289.528285378615, 6305.4009869062365, 6308.243010838729, 6065.090236464522, 6341.2707538633185, 6345.397187267202, 6357.459615932651, 6362.1093046973465, 5747.195392645033, 5816.88366232673, 5871.236739223917, 5872.031114865999, 5878.617889837147, 6425.550211485244, 6465.224173099258, 6470.4823614110755, 6471.417572418505, 6475.028745688184, 6170.10533472653, 6232.0963460298, 6233.3143354231, 6246.4378205960775, 6247.0, 6056.909146362808, 6083.452489275986, 6132.122542660378, 6165.969170154211, 6169.321374648785, 6220.377962203744, 6256.74973001084, 6258.968705156445, 6260.7495700171485, 6262.968685156545, 6035.486983606354, 6056.697165141725, 6057.273127224673, 6120.751898419022, 6154.5384096192965, 4726.763661816895, 4754.762261886961, 4776.761161941889, 4799.904001920012, 4813.4945830687975, 4164.06308580573, 4172.979135104305, 4254.29804082323, 4277.208716387416, 4281.165172791338, 5981.252343457068, 6012.338642749294, 6059.030555111161, 6095.116208149882, 6109.969450152721, 6306.369363063749, 6348.873022539616, 6351.872962540816, 6356.046593010993, 6356.618602883842, 5867.589268751147, 6062.484688801413, 6078.635281883101, 6079.665618390943, 6080.483158931452, 5247.790088396498, 5254.474552544715, 5259.105951988171, 5336.573074154058, 5352.812651557177, 6511.902321465182, 6519.282878883224, 6521.543491955613, 6523.217213934358, 6528.053432252392, 6237.4698150657705, 6244.406781355764, 6280.937190628218, 6292.433680968735, 6293.0, 6268.27914789805, 6291.622502649856, 6451.806445806634, 6455.354464553507, 6527.608343499405, 6249.750009999549, 6252.280987686474, 6259.6870156492905, 6263.123162757127, 6274.215723034621, 6368.4586810121655, 6374.362563743589, 6374.649394283359, 6376.202974628171, 6380.617162970051, 5950.613210141327, 5992.280926288873, 6121.265448146251, 6128.816135515942, 6211.65835879031, 6161.137440758208, 6218.502519798315, 6225.097360882556, 6231.844203894939, 6231.906521402092, 6133.601315914451, 6165.907511387422, 6167.753289868355, 6173.32093469709, 6193.0, 5942.702864856827, 5971.193888824953, 6015.368386319374, 6130.816075517741, 6140.785072522441, 6079.270487541523, 6081.361457046946, 6087.878242435215, 6097.298810636834, 6114.602550834182], "value": 5773.854904723137, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.535640937233, 832.4048305461594, 842.5744998775617, 917.1241464401493, 930.5393830054124, 29995.400091998148, 42354.0, 42483.36274955878, 42496.0, 42603.57396426035, 43840.561594384046, 43864.3420348695, 43992.56007439925, 44039.0, 44118.11763764731, 7656.961715191416, 7868.881966770503, 9107.225885799715, 9240.075992400773, 9240.815183696322, 10258.333208341433, 10738.248322617419, 10742.677719668383, 10743.247972641919, 10746.73133171673, 10938.453077346141, 10967.0, 10968.122550196005, 10980.286281391684, 10984.340939543614, 10830.40432776197, 10877.67366978992, 10914.50884710182, 10926.399048052353, 10927.016568508872, 10429.9478502607, 10477.371357718484, 10495.895041049644, 10496.317739346994, 10497.370157790481, 40890.182196355905, 40994.590054099375, 41020.58979410197, 41050.1789964202, 41344.37983430251, 4209.074003719177, 4240.321548552248, 4280.700350975402, 4371.125774845043, 4382.035952090535, 29099.56350654742, 29360.41279174426, 29461.85269073641, 29508.0, 30944.226394340098, 3641.581218159919, 3751.362268414376, 3762.49206357141, 3781.7920014399197, 3829.617038296176, 228393.0, 228861.0, 229195.0, 230100.84949575312, 231410.0, 51844.481555183964, 51867.48132518702, 51869.0, 51915.740421298404, 51969.0, 54038.45961540413, 54203.0, 54234.45765542294, 54266.45733542614, 54279.45720542823, 16498.42255521075, 16754.58113547171, 16773.83226167747, 16777.748333775, 16789.16054197285, 13177.341132943315, 13370.532031378854, 14229.0, 14813.777793333109, 14982.550523484313, 13686.0, 13802.0, 13862.93068534651, 13911.930440347936, 14019.369128389248, 5581.609287349927, 5626.718664066781, 5656.547476201894, 5680.11958146488, 5693.430656934273, 998.2463240253562, 1003.3277703938379, 1006.225206590921, 1014.5637375928421, 1014.8832884218261, 28008.0, 28016.29959251035, 28180.43639127206, 28453.715462845106, 28530.85734571314, 1160.93614851184, 1172.9824052639217, 1187.893089621938, 1208.099965525689, 1208.1301462946665, 14356.928215358856, 14513.201773902325, 14535.563933081812, 14574.708505829823, 14600.0, 307050.0, 307066.0, 307081.0, 307295.0, 307648.0, 47551.762241188575, 47555.0, 47634.0, 47678.5232147681, 47771.0, 1417.6314158318912, 1435.7343891380128, 1446.1178681004578, 1446.5009571697747, 1450.0864455393034, 24402.26793196207, 25114.874425627753, 25460.108896188543, 25945.610815838154, 26047.218583442525, 35565.64434355623, 35577.0, 35589.82205088958, 35675.0, 35700.0, 275451.0, 277929.0, 279712.0, 280483.0, 281244.0, 24650.753492464846, 24823.50352992966, 27744.44511109807, 27801.860990694917, 28351.858240708665, 37123.07192320214, 37485.81257093697, 37549.62450375461, 38198.2360352797, 38520.2295954085, 30471.276952072465, 30634.0, 31066.223344416572, 31280.530792038593, 31342.529862052543, 31042.379152417278, 31211.843940780152, 31401.842990785815, 31761.20596985094, 32007.199820004687, 35642.46536302009, 35660.821695891354, 35732.82133589315, 36857.0, 36899.07752306214, 29633.40733185281, 29855.850720746257, 30117.247068823453, 30262.0, 30410.695893041673, 23769.16807911715, 24834.00663973421, 24922.252332430056, 25032.2464528898, 25367.112151074623], "value": 18301.094055835332, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.43624161073797, 460.1073916601794, 460.5676346562191, 474.94300683917925, 490.2768416585536, 19724.0, 20798.89600551999, 21036.57926841462, 21378.89310553445, 21475.570488590238, 22340.776592234073, 22420.77579224207, 22619.98210080546, 22786.772132278715, 22816.31551053467, 6539.967300163493, 6540.705668244928, 6805.489588280874, 6806.7957961261245, 6868.484863635223, 6702.363275488845, 6812.489063320246, 6814.35263649955, 6815.079964204819, 7562.205968373324, 7851.607419629025, 7887.881681774749, 8036.316913062396, 8051.2351326623875, 8058.798530036737, 7824.452288339819, 7976.641051152712, 8029.116797152308, 8054.637541310655, 8057.59712014397, 7607.163212046726, 7857.921420785833, 7866.370690344816, 7920.841583168304, 7935.166807485246, 28978.855105724335, 29198.0, 29498.0, 29611.259718506994, 29691.851540742373, 3181.618205815317, 3186.601674790651, 3367.461206206995, 3568.9107772305642, 3593.8077772558718, 17195.226214820363, 17531.73702394465, 17664.381746638806, 18374.816251837445, 18863.339783107524, 2280.669302951063, 2387.188355958965, 2393.4734358441115, 2439.524292762912, 2440.5240978009297, 115158.42420787926, 119514.4024279873, 119711.99999999913, 119976.0, 120442.39778801138, 21942.341729748132, 22138.667919981213, 22209.44476388071, 22661.28064632222, 22989.080436782675, 43341.133177336276, 43412.0, 43598.782006089765, 43642.12715745667, 44116.77941610271, 9966.700998970042, 10346.53440595175, 10494.370337779757, 10554.366737995591, 10784.568617255221, 8884.555772211364, 8894.555272236492, 8901.599428025755, 9101.590428430736, 9105.226055785333, 9081.182693557612, 9482.762930926781, 9543.761405964906, 9609.711708648752, 9686.612535498642, 3475.117459968748, 3690.760100593453, 3770.3967365221433, 3775.565809931838, 3789.696824254053, 972.2416515118077, 974.5273542331963, 980.0983095552109, 984.7095106943469, 997.870276864003, 20992.160313587287, 21174.0, 21242.893785530974, 21309.573808524052, 21340.893295533733, 985.1724551376734, 992.8014397120605, 999.9950000250097, 1011.1001208924064, 1014.2291858187683, 10694.679159625224, 11521.423928803526, 11630.53477860876, 11662.650120496399, 11750.823737643943, 646585.0, 647283.0, 650974.0, 659949.0, 662703.0, 104408.0, 107775.0, 112456.43771781088, 113190.43404782923, 113924.0, 1296.1510210811932, 1298.0913360647585, 1332.9066965312336, 1337.518493342396, 1341.4097796969302, 17844.19701113479, 18146.364877229233, 18407.0, 18488.82265275708, 18661.90669046646, 48388.27417588668, 48514.51485485241, 48901.2664810021, 48902.26646600375, 49486.0, 66260.66869665813, 66380.0, 66968.33031669816, 67725.66137169283, 68895.65552172408, 9363.953180234055, 11119.610813621484, 11759.176857620227, 12375.62873113808, 12544.87455125437, 12958.0, 13182.868171318163, 13452.93273533626, 13508.797368039684, 13527.932360338134, 11934.940325298317, 12020.098492613264, 12078.879211207775, 12163.026957843529, 12488.687782805502, 11174.050205732603, 11209.271397359145, 11375.829362559734, 11390.316581005167, 11591.072714183014, 12071.698207544881, 12192.0, 12310.507579696712, 12364.072694548126, 12569.245845249314, 11297.604583839855, 11983.400829958642, 12087.456064477296, 12105.0, 12253.142280040312, 12503.124781265224, 12522.561710340458, 12533.185342952678, 12555.183913045623, 12578.811317830423], "value": 11842.55271142925, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.5068276785903, 1539.122700060965, 1559.91420471874, 1579.6603730198008, 1596.1939220693555, 9601.183899368554, 11295.435228238586, 11514.424278786058, 11961.760764784722, 11982.460789264482, 11736.941315293412, 11815.7636847263, 11816.172867899251, 11844.466998985044, 11850.940745296262, 2065.5249292662697, 2141.0579345088113, 2472.295395812194, 2520.5085008423366, 2524.7601477859666, 2470.3700556358194, 2566.538023155831, 2579.432524844531, 2588.0812311629384, 2594.247668176227, 2480.1815400917653, 2496.588062969608, 2506.348349429152, 2509.8494090354548, 2513.0576033987254, 2438.67077944477, 2445.0342114864707, 2457.5207834472285, 2465.359006658273, 2473.4434752180796, 2338.5089131282452, 2344.2732752846673, 2347.730011048734, 2348.330725743155, 2365.1485465232504, 11693.298402095901, 11941.10441716866, 12006.759864802654, 12024.158308918379, 12029.819552706716, 5933.762649494015, 6044.818655440344, 6073.028315469548, 6101.72542235596, 6102.755889764404, 3282.720968717662, 3299.15871452778, 3344.749143814199, 3587.2287458196615, 3650.324689932371, 1277.3613193403298, 1293.1465232946211, 1326.9469221231138, 1328.2694518015053, 1338.3910320804011, 17727.468175954742, 17829.0, 17867.017314047724, 17883.28466861324, 17886.821131788645, 4905.950940490549, 5101.801076746961, 5128.717920514333, 5148.510891465305, 5149.6910185389, 14708.852911470962, 14764.261786910827, 14782.926085369505, 14784.186869722053, 14785.852141478663, 6165.660888651168, 6170.043643235216, 6177.289611694712, 6181.227346581677, 6185.165002724664, 4647.07058588277, 4788.928166077442, 5345.118055520873, 5416.404195538487, 5491.203775452537, 4328.567143285709, 4340.65274778017, 4365.738055716667, 4421.204183247047, 4428.180786554433, 1183.976320473603, 1207.0464333176867, 1221.84116064911, 1222.5048855213652, 1227.2084505494086, 966.569876404993, 973.2360097323567, 973.6543527047796, 980.0, 986.8174387738147, 6694.832629184212, 6725.293844146392, 6782.491313151533, 6809.250982391933, 6959.338862808025, 1035.0, 1055.3087727538475, 1062.1396668698378, 1072.5602502973743, 1076.9407682577378, 4315.0, 4413.8234470621455, 4414.713043652152, 4425.889352766142, 4451.554844515523, 14200.28998550089, 14687.26563671812, 14788.0, 14805.03767255125, 14807.48173813933, 13375.531856385174, 13857.4457021718, 14425.639359016108, 14463.0, 14468.131912085308, 10599.62901298451, 10790.08284295828, 10801.081908037904, 10802.135829133807, 10816.134709223086, 6962.303769622998, 7155.856882862209, 7167.641617918979, 7191.137063552407, 7217.278272172741, 13009.0, 13155.934220328838, 13315.467381305027, 13411.329433528479, 13486.05597608158, 13349.799753003519, 13503.527376542166, 13623.931880340535, 13681.521146759816, 13741.450341986209, 8462.534560599226, 8644.438111522732, 9548.665796697082, 9998.650047248311, 10255.948720256649, 11016.17378696585, 11165.944170279097, 11228.0, 11255.943720281346, 11262.155338349492, 9207.76980575491, 9300.953495232481, 9322.720318390458, 9410.670626528039, 9510.904890951279, 8946.194842463949, 9056.909430905871, 9069.591868366071, 9118.0, 9191.77020574491, 8568.35737319691, 8706.47761134334, 8765.824683506422, 8773.38586298953, 8804.559772011502, 6152.846178845564, 6183.598066125687, 6238.937610623836, 6288.339724328879, 6438.420542151229, 5975.43233392827, 6057.242844644419, 6062.242219722535, 6113.938860611515, 6161.144540710451], "value": 5459.293706680848, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.9793510324483, 421.38267438203025, 435.4339358833516, 437.1628331744709, 441.9999999999998, 5233.528982391584, 5302.575793936486, 5364.651297665659, 5379.354477462705, 5410.296661434018, 5214.478552144793, 5227.817026404067, 5238.297553295185, 5257.05488969081, 5273.393559740631, 4262.1688770689725, 4433.600975912167, 4477.686561940665, 4486.327050942352, 4513.932291015637, 4453.77521181676, 4527.818887244506, 4527.909441811162, 4540.4778450478125, 4564.5435456454425, 4498.550144985508, 4521.321801729734, 4524.298733696282, 4528.547145285478, 4562.361269422284, 4312.951348109638, 4442.844500442469, 4476.037651904856, 4494.325851122325, 4523.2762757958735, 4318.460192475941, 4324.329728892027, 4362.516744306951, 4391.648668106575, 4427.579379958899, 5240.6069544784295, 5246.055709972204, 5254.868628284324, 5272.182811664198, 5279.973600132013, 5228.529432351107, 5277.419483856811, 5316.601254905898, 5343.091674415358, 5356.625036247464, 5498.257735205736, 5532.585056120807, 5548.6393384430285, 5576.275084239063, 5618.7190640468225, 4940.48124946879, 4940.555350018516, 4968.826091086795, 5000.0999820032375, 5000.749962501896, 5533.6403133796175, 5610.0, 5674.574406919456, 5856.941430585683, 5880.441358070976, 4949.826756063592, 5059.494050594984, 5072.9746351268, 5087.771050302746, 5097.643164978416, 5175.223716442489, 5187.429382767892, 5192.221166825007, 5193.246979187997, 5253.789848406097, 5048.116579598629, 5257.767497164743, 5313.840584782385, 5373.516383525502, 5391.81128660495, 5555.444455554412, 5589.636673616202, 5608.887822243614, 5611.717145469865, 5625.831225063254, 1791.5162906015303, 1796.7035439152394, 1799.9560255052038, 1806.1872157529165, 1806.449033044934, 1427.9214643194723, 1462.6197188730794, 1468.1411374345914, 1488.5906375746756, 1504.2704288420107, 5550.4467944745065, 5565.805196818092, 5573.944260557424, 5575.693336866511, 5579.972100139474, 5624.353199382042, 5631.0, 5665.886682266331, 5687.829365119053, 5736.799212027559, 5260.842174734764, 5401.405115251014, 5404.621676482687, 5492.450754924475, 5520.702634880799, 2937.985310073436, 2971.202422534379, 2984.4478771427357, 2995.9400811983637, 3001.5347621118763, 5900.940990590039, 5905.350411454737, 5914.615549989237, 5928.555358348055, 5977.551683623746, 5525.585581081355, 5766.538676905838, 5781.71091445426, 5797.449242321973, 5810.564207684441, 5861.267341582302, 5886.823395298148, 5903.498202652822, 5906.468417842415, 5912.260967379078, 5401.891962160814, 5618.6628802272, 5640.435956404327, 5710.4004079572705, 5712.914306285492, 5394.57444522723, 5615.8876822464135, 5625.746841392065, 5631.887362252814, 5652.417323149525, 5726.656400615976, 5783.681897495678, 5912.763489460545, 5915.260592425947, 5919.845630102089, 4693.460252071056, 4733.656839864289, 4763.880902977453, 4766.523347665345, 4767.713937163781, 5414.0, 5613.578981576316, 5633.2395126657375, 5735.397783232867, 5739.196512488338, 5431.185322201702, 5484.287042684426, 5517.751701173376, 5523.8066667666435, 5535.951697871813, 5462.0, 5581.330240371181, 5598.580106492111, 5611.186377975089, 5623.891040859678, 5358.437364076716, 5420.891582168413, 5436.157395603688, 5442.700651464207, 5467.562594992471, 5475.09660905946, 5510.476504732044, 5549.556035517229, 5566.192902029264, 5575.275214222125, 5196.844094677166, 5417.918731218956, 5453.818472610988, 5483.341998959991, 5546.362168350692], "value": 4547.719928073065, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.7078857794058, 873.1399571422152, 878.2710350409156, 894.8881389826272, 933.822573710995, 17252.74120888186, 17588.296468141274, 17868.195931183094, 17926.82073179268, 18591.0, 17849.0, 18735.15691793869, 18773.0, 18871.433856984313, 18913.0, 863.568215892054, 880.7049638371153, 883.9116088391157, 896.3232759266745, 899.4917871402637, 886.4149661223594, 888.4979986307748, 888.6578667213105, 888.8044630181349, 889.6841621224469, 925.9074092590721, 928.767808047988, 928.9966835817335, 929.7675581104724, 930.5951910918739, 922.3267015078962, 923.7921467669788, 925.0934084597133, 925.5742358515091, 926.2312280806948, 674.2818897873749, 674.9730010799562, 675.3988949834651, 682.2018238660779, 682.3142741544765, 17916.641667166583, 18051.548711282325, 18080.547986300448, 18506.0, 18693.15880785368, 17331.266873312696, 17466.301347946068, 17643.73534396985, 17724.822751772575, 17728.29086836525, 13872.791908121386, 13983.23092229927, 13989.510367137204, 13996.650083747885, 14059.437622495088, 4117.8970525736795, 4127.484064491939, 4143.4199212110325, 4207.242696314662, 4266.466691663542, 50119.49880501221, 55104.17343739847, 80326.0, 91806.99999999933, 92151.0, 13755.931220343835, 13791.241481718402, 13826.239556824472, 14044.227567483886, 14060.296985150906, 25685.74314256871, 25723.742762572136, 25730.0, 26093.0, 26097.608535871976, 9016.143466370684, 9085.13691199335, 9189.77025574366, 9305.674301399418, 9366.344355895022, 4974.602031837517, 5076.974615126975, 5150.253213284052, 5178.300929374562, 5182.8963420732125, 3735.7384983050924, 3827.004978705557, 3830.3105441020884, 3830.3296923038356, 3830.616938306147, 637.6269882119012, 637.7500099804342, 645.5255387290432, 652.8400541867273, 654.1104098426136, 848.7708318753902, 854.5171977832505, 866.1252135343349, 879.2482427524502, 880.1459948384663, 10896.891031089746, 11734.354610496333, 11756.23584467007, 12181.08641851847, 12361.93819030899, 855.8545047341843, 861.9138086191456, 875.2472873328932, 882.7969566999626, 894.1282249806517, 5716.101295232617, 5805.603721822697, 5813.476787089182, 5870.618409803349, 5926.614770039933, 82103.0, 82587.58706206431, 82979.0, 83423.0, 86041.13958860456, 59230.11154832681, 65437.0, 65473.67263163749, 69662.0, 73054.26945730581, 60049.39950600438, 67894.32105678979, 71680.2831971684, 73704.63147684227, 76363.23636763672, 11115.44422778874, 12240.0, 12240.204386714835, 12449.626511204679, 12688.429020694275, 45698.77150614226, 46710.06579868316, 46744.53255467538, 46936.53063469322, 47189.764051179525, 55355.0, 55916.0, 56421.0, 56453.0, 56696.71651641715, 14539.92730036343, 14836.62908427298, 14886.37326593986, 15045.774313385527, 15091.0, 17039.914800426413, 17046.744298835278, 17088.487345379657, 17093.914530427268, 17252.223649936033, 14847.183404912834, 15248.161351125795, 15459.536213913601, 15498.5350439487, 15588.92205538965, 15021.533673312479, 15522.84477155214, 15579.766303505683, 15850.762238566662, 16116.758248626045, 16203.351865925231, 16514.42199523059, 16548.669026619642, 17176.82823171752, 17324.0, 11604.593839215924, 12093.213941093802, 12423.254604723745, 12489.18820276679, 12570.371481426075, 13337.53318633843, 13344.0, 13448.327583620585, 13469.528566500518, 13674.179549227078], "value": 8214.954651085853, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0, 112.0, 114.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 115.0, 154.0, 117.0, 115.0, 115.0, 115.0, 134.0, 117.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 159.0, 161.0, 161.0, 162.0, 159.0, 160.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 114.0, 76.0, 76.0, 76.0, 75.0, 90.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 101.0, 82.0, 82.0, 83.0, 100.0, 82.0, 82.0, 122.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 148.0, 146.0, 146.0, 147.0, 146.0, 149.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 54.0, 54.0, 53.0, 55.0, 55.0, 54.0, 56.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 55.0, 52.0, 53.0, 51.0, 52.0, 53.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 76.0, 78.0, 75.0, 84.0, 79.0, 82.0, 82.0, 76.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 130.0, 122.0, 127.0, 120.0, 120.0, 118.0, 118.0, 163.0, 129.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 159.0, 155.0, 157.0, 160.0, 157.0, 163.0, 158.0, 158.0, 161.0], "value": 158.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 66.0, 59.0, 59.0, 60.0, 63.0, 60.0, 59.0], "value": 61.1, "unit": "score"}]}]} 01:57:02 INFO - Return code: 0 01:57:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:57:02 ERROR - # TBPL SUCCESS # 01:57:02 INFO - Running post-action listener: _resource_record_post_action 01:57:02 INFO - [mozharness: 2017-04-23 08:57:02.832299Z] Finished run-tests step (success) 01:57:02 INFO - Running post-run listener: _resource_record_post_run 01:57:02 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 7569408; Write bytes: 374644736; Read time: 1192; Write time: 275352 01:57:02 INFO - TinderboxPrint: CPU usage
13.1% 01:57:02 INFO - TinderboxPrint: I/O read bytes / time
7,569,408 / 1,192 01:57:02 INFO - TinderboxPrint: I/O write bytes / time
374,644,736 / 275,352 01:57:02 INFO - TinderboxPrint: CPU idle
8,277.6 (86.9%) 01:57:02 INFO - TinderboxPrint: CPU user
1,203.3 (12.6%) 01:57:02 INFO - TinderboxPrint: Swap in / out
0 / 0 01:57:02 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118722560; Read time: 80; Write time: 107668 01:57:02 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 5820416; Write bytes: 255922176; Read time: 1072; Write time: 167684 01:57:03 INFO - Running post-run listener: _upload_blobber_files 01:57:03 INFO - Blob upload gear active. 01:57:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:57:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:57:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:57:03 INFO - (blobuploader) - INFO - Open directory for files ... 01:57:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:57:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:57:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:07 INFO - (blobuploader) - INFO - Done attempting. 01:57:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:57:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:57:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:08 INFO - (blobuploader) - INFO - Done attempting. 01:57:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:57:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:57:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:09 INFO - (blobuploader) - INFO - Done attempting. 01:57:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:57:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:57:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:10 INFO - (blobuploader) - INFO - Done attempting. 01:57:10 INFO - (blobuploader) - INFO - Iteration through files over. 01:57:10 INFO - Return code: 0 01:57:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:57:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:57:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1c1b78382429e78f1087ca75211041e3d290510b87f0c5232051f81b59ea294776f341afbbdf28f5491896a998bebea9ef422a25ab682eab7e03c5e3f17660e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9b9e81685b1a05edf0d635a6b512d69afd4e4a5a4d3e462802f5e2fad742570db91f4b4972e6bcf88080284a2169f3ae439c5e3d32995bce3e76318bb5546a45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6c683adbbcfb909ef8dddef77bd9e08f63fa9baa54de30b9e73de5e5ef162362b4c6af4cf894a8a4c79f567093297e480f26511076a8ef5cdb20bf1c6fc4fe1d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d19edc1af9c4f6f47b5f9ac8d98deebf5aaa79498395b1fabfc680919b6f9fa70529a42499e2c6ebef40f735ec4ca2334ef01e89003d51a05044c417f3ae0f1a"} 01:57:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:57:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:57:10 INFO - Contents: 01:57:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1c1b78382429e78f1087ca75211041e3d290510b87f0c5232051f81b59ea294776f341afbbdf28f5491896a998bebea9ef422a25ab682eab7e03c5e3f17660e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9b9e81685b1a05edf0d635a6b512d69afd4e4a5a4d3e462802f5e2fad742570db91f4b4972e6bcf88080284a2169f3ae439c5e3d32995bce3e76318bb5546a45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6c683adbbcfb909ef8dddef77bd9e08f63fa9baa54de30b9e73de5e5ef162362b4c6af4cf894a8a4c79f567093297e480f26511076a8ef5cdb20bf1c6fc4fe1d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d19edc1af9c4f6f47b5f9ac8d98deebf5aaa79498395b1fabfc680919b6f9fa70529a42499e2c6ebef40f735ec4ca2334ef01e89003d51a05044c417f3ae0f1a"} 01:57:10 INFO - Running post-run listener: copy_logs_to_upload_dir 01:57:10 INFO - Copying logs to upload dir... 01:57:10 INFO - mkdir: /builds/slave/test/build/upload/logs 01:57:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.209650 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-04-23 01:57:10.810655) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-23 01:57:10.822233) ========= 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=8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1c1b78382429e78f1087ca75211041e3d290510b87f0c5232051f81b59ea294776f341afbbdf28f5491896a998bebea9ef422a25ab682eab7e03c5e3f17660e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9b9e81685b1a05edf0d635a6b512d69afd4e4a5a4d3e462802f5e2fad742570db91f4b4972e6bcf88080284a2169f3ae439c5e3d32995bce3e76318bb5546a45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6c683adbbcfb909ef8dddef77bd9e08f63fa9baa54de30b9e73de5e5ef162362b4c6af4cf894a8a4c79f567093297e480f26511076a8ef5cdb20bf1c6fc4fe1d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d19edc1af9c4f6f47b5f9ac8d98deebf5aaa79498395b1fabfc680919b6f9fa70529a42499e2c6ebef40f735ec4ca2334ef01e89003d51a05044c417f3ae0f1a"} build_url:https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/Kf10F7y9THCY9AYof-ogXw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1c1b78382429e78f1087ca75211041e3d290510b87f0c5232051f81b59ea294776f341afbbdf28f5491896a998bebea9ef422a25ab682eab7e03c5e3f17660e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9b9e81685b1a05edf0d635a6b512d69afd4e4a5a4d3e462802f5e2fad742570db91f4b4972e6bcf88080284a2169f3ae439c5e3d32995bce3e76318bb5546a45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6c683adbbcfb909ef8dddef77bd9e08f63fa9baa54de30b9e73de5e5ef162362b4c6af4cf894a8a4c79f567093297e480f26511076a8ef5cdb20bf1c6fc4fe1d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d19edc1af9c4f6f47b5f9ac8d98deebf5aaa79498395b1fabfc680919b6f9fa70529a42499e2c6ebef40f735ec4ca2334ef01e89003d51a05044c417f3ae0f1a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-23 01:57:10.854949) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:57:10.855348) ========= 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=8887e36bb8e381bfd44124ac000001a8-1492917531.887046-1926163985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:57:10.908693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-23 01:57:10.909070) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-23 01:57:10.909395) ========= ========= Total master_lag: 0.17 =========