builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1483824427.5 results: success (0) revision: 77eebb6c80cd5249b9a43bbcdefdaaa9d9845d1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.497763) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.498209) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.498480) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.512665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.512922) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-07 13:27:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-01-07 13:27:07 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098558 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.624992) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.625309) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020396 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.674905) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:07.675246) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 77eebb6c80cd5249b9a43bbcdefdaaa9d9845d1e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 77eebb6c80cd5249b9a43bbcdefdaaa9d9845d1e --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=74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-07 13:27:07,994 truncating revision to first 12 chars 2017-01-07 13:27:07,994 Setting DEBUG logging. 2017-01-07 13:27:07,994 attempt 1/10 2017-01-07 13:27:07,994 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/77eebb6c80cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 13:27:08,404 unpacking tar archive at: mozilla-central-77eebb6c80cd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.725588 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:08.413885) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:08.414235) ========= script_repo_revision: 77eebb6c80cd5249b9a43bbcdefdaaa9d9845d1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:08.414654) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:08.414924) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 13:27:08.424782) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-07 13:27:08.425199) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:27:08 INFO - MultiFileLogger online at 20170107 13:27:08 in /builds/slave/test 13:27:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 13:27:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:27:08 INFO - {'append_to_log': False, 13:27:08 INFO - 'base_work_dir': '/builds/slave/test', 13:27:08 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 13:27:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:27:08 INFO - 'branch': 'Firefox-Non-PGO', 13:27:08 INFO - 'buildbot_json_path': 'buildprops.json', 13:27:08 INFO - 'config_files': ('talos/linux_config.py',), 13:27:08 INFO - 'default_actions': ('clobber', 13:27:08 INFO - 'read-buildbot-config', 13:27:08 INFO - 'download-and-extract', 13:27:08 INFO - 'populate-webroot', 13:27:08 INFO - 'create-virtualenv', 13:27:08 INFO - 'install', 13:27:08 INFO - 'run-tests'), 13:27:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:27:08 INFO - 'download_minidump_stackwalk': True, 13:27:08 INFO - 'download_symbols': 'ondemand', 13:27:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:27:08 INFO - 'tooltool.py': '/tools/tooltool.py', 13:27:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:27:08 INFO - '/tools/misc-python/virtualenv.py')}, 13:27:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:27:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:27:08 INFO - 'installer_path': 'installer.exe', 13:27:08 INFO - 'log_level': 'info', 13:27:08 INFO - 'log_name': 'talos', 13:27:08 INFO - 'log_to_console': True, 13:27:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:27:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:27:08 INFO - 'opt_config_files': (), 13:27:08 INFO - 'pip_index': False, 13:27:08 INFO - 'sps_profile': False, 13:27:08 INFO - 'sps_profile_interval': 0, 13:27:08 INFO - 'suite': 'dromaeojs', 13:27:08 INFO - 'system_bits': '32', 13:27:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:27:08 INFO - 'title': 'talos-linux64-ix-042', 13:27:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:27:08 INFO - 'use_talos_json': True, 13:27:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:27:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:27:08 INFO - 'work_dir': 'build'} 13:27:08 INFO - [mozharness: 2017-01-07 21:27:08.840692Z] Running clobber step. 13:27:08 INFO - Running pre-action listener: _resource_record_pre_action 13:27:08 INFO - Running main action method: clobber 13:27:08 INFO - rmtree: /builds/slave/test/build 13:27:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:27:09 INFO - Running post-action listener: _resource_record_post_action 13:27:09 INFO - [mozharness: 2017-01-07 21:27:09.287027Z] Finished clobber step (success) 13:27:09 INFO - [mozharness: 2017-01-07 21:27:09.287130Z] Running read-buildbot-config step. 13:27:09 INFO - Running pre-action listener: _resource_record_pre_action 13:27:09 INFO - Running main action method: read_buildbot_config 13:27:09 INFO - Using buildbot properties: 13:27:09 INFO - { 13:27:09 INFO - "project": "", 13:27:09 INFO - "product": "firefox", 13:27:09 INFO - "who": "ryanvm@gmail.com", 13:27:09 INFO - "installer_path": "public/build/target.tar.bz2", 13:27:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 13:27:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 13:27:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 13:27:09 INFO - "stage_platform": "linux64", 13:27:09 INFO - "basedir": "/builds/slave/test", 13:27:09 INFO - "buildnumber": 769, 13:27:09 INFO - "platform": "ubuntu64_hw", 13:27:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:27:09 INFO - "slavebuilddir": "test", 13:27:09 INFO - "taskId": "HVQyZ3IcSEOKnALbC6_wag", 13:27:09 INFO - "branch": "mozilla-central", 13:27:09 INFO - "script_repo_revision": "production", 13:27:09 INFO - "revision": "77eebb6c80cd5249b9a43bbcdefdaaa9d9845d1e", 13:27:09 INFO - "slavename": "talos-linux64-ix-042", 13:27:09 INFO - "repo_path": "mozilla-central" 13:27:09 INFO - } 13:27:09 INFO - Finding installer, test and symbols from parent task. 13:27:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HVQyZ3IcSEOKnALbC6_wag'}, attempt #1 13:27:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HVQyZ3IcSEOKnALbC6_wag'}, attempt #1 13:27:10 INFO - Task dependencies: XTphTwlqQl2W1JYiXAOyaw 13:27:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw'}, attempt #1 13:27:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.tar.bz2 13:27:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/test_packages.json 13:27:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.crashreporter-symbols.zip 13:27:10 INFO - Running post-action listener: _resource_record_post_action 13:27:10 INFO - [mozharness: 2017-01-07 21:27:10.682338Z] Finished read-buildbot-config step (success) 13:27:10 INFO - [mozharness: 2017-01-07 21:27:10.682555Z] Running download-and-extract step. 13:27:10 INFO - Running pre-action listener: _resource_record_pre_action 13:27:10 INFO - Running main action method: download_and_extract 13:27:10 INFO - mkdir: /builds/slave/test/build/tests 13:27:10 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')]} 13:27:10 INFO - trying https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.test_packages.json 13:27:10 INFO - Downloading https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:27:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:27:11 INFO - Downloaded 896 bytes. 13:27:11 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:27:11 INFO - Using the following test package requirements: 13:27:11 INFO - {u'common': [u'target.common.tests.zip'], 13:27:11 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:27:11 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:27:11 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:27:11 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:27:11 INFO - u'mozbase': [u'target.common.tests.zip'], 13:27:11 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:27:11 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:27:11 INFO - u'web-platform': [u'target.common.tests.zip', 13:27:11 INFO - u'target.web-platform.tests.zip'], 13:27:11 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:27:11 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:27:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.common.tests.zip 13:27:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:27:11 INFO - Fetch https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.common.tests.zip into memory 13:27:12 INFO - Expected file size: 20251352 13:27:12 INFO - Obtained file size: 20251352 13:27:16 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 13:27:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.common.tests.zip 13:27:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:27:16 INFO - Fetch https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.common.tests.zip into memory 13:27:16 INFO - Expected file size: 20251352 13:27:16 INFO - Obtained file size: 20251352 13:27:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.talos.tests.zip 13:27:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:27:21 INFO - Fetch https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.talos.tests.zip into memory 13:27:21 INFO - Expected file size: 14403537 13:27:21 INFO - Obtained file size: 14403537 13:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:22 INFO - trying https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.tar.bz2 13:27:22 INFO - Downloading https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:27:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:27:23 INFO - Downloaded 55904454 bytes. 13:27:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.tar.bz2 13:27:23 INFO - mkdir: /builds/slave/test/properties 13:27:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:27:23 INFO - Writing to file /builds/slave/test/properties/build_url 13:27:23 INFO - Contents: 13:27:23 INFO - build_url:https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.tar.bz2 13:27:23 INFO - Running post-action listener: _resource_record_post_action 13:27:23 INFO - Running post-action listener: set_extra_try_arguments 13:27:23 INFO - [mozharness: 2017-01-07 21:27:23.261548Z] Finished download-and-extract step (success) 13:27:23 INFO - [mozharness: 2017-01-07 21:27:23.261645Z] Running populate-webroot step. 13:27:23 INFO - Running pre-action listener: _resource_record_pre_action 13:27:23 INFO - Running main action method: populate_webroot 13:27:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:27:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:27:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:27:23 INFO - '--tppagecycles', 13:27:23 INFO - '7'], 13:27:23 INFO - 'tests': ['tsvgm']}}, 13:27:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:27:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:27:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:27:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:27:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:27:23 INFO - 'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:27:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:27:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:27:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:27:23 INFO - 'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['damp', 'tps']}, 13:27:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:27:23 INFO - 'tests': ['damp', 'tps']}, 13:27:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['dromaeo_dom']}, 13:27:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:27:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['basic_compositor_video']}, 13:27:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:27:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['a11yr', 13:27:23 INFO - 'ts_paint', 13:27:23 INFO - 'tpaint', 13:27:23 INFO - 'sessionrestore', 13:27:23 INFO - 'sessionrestore_no_auto_restore', 13:27:23 INFO - 'tabpaint']}, 13:27:23 INFO - 'other-e10s': {'tests': ['a11yr', 13:27:23 INFO - 'ts_paint', 13:27:23 INFO - 'tpaint', 13:27:23 INFO - 'sessionrestore', 13:27:23 INFO - 'sessionrestore_no_auto_restore', 13:27:23 INFO - 'tabpaint']}, 13:27:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:27:23 INFO - 'ts_paint', 13:27:23 INFO - 'tpaint', 13:27:23 INFO - 'sessionrestore', 13:27:23 INFO - 'sessionrestore_no_auto_restore', 13:27:23 INFO - 'tabpaint']}, 13:27:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:27:23 INFO - 'ts_paint', 13:27:23 INFO - 'tpaint', 13:27:23 INFO - 'sessionrestore', 13:27:23 INFO - 'sessionrestore_no_auto_restore', 13:27:23 INFO - 'tabpaint']}, 13:27:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['a11yr', 13:27:23 INFO - 'ts_paint', 13:27:23 INFO - 'tpaint', 13:27:23 INFO - 'sessionrestore', 13:27:23 INFO - 'sessionrestore_no_auto_restore', 13:27:23 INFO - 'tabpaint']}, 13:27:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['a11yr', 13:27:23 INFO - 'ts_paint', 13:27:23 INFO - 'tpaint', 13:27:23 INFO - 'sessionrestore', 13:27:23 INFO - 'sessionrestore_no_auto_restore', 13:27:23 INFO - 'tabpaint']}, 13:27:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['tsvgx', 13:27:23 INFO - 'tsvgr_opacity', 13:27:23 INFO - 'tart', 13:27:23 INFO - 'tscrollx', 13:27:23 INFO - 'cart', 13:27:23 INFO - 'tsvg_static']}, 13:27:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:27:23 INFO - 'tsvgr_opacity', 13:27:23 INFO - 'tart', 13:27:23 INFO - 'tscrollx', 13:27:23 INFO - 'cart', 13:27:23 INFO - 'tsvg_static']}, 13:27:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:27:23 INFO - 'talos_options': ['--disable-e10s'], 13:27:23 INFO - 'tests': ['tp5o']}, 13:27:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:27:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:27:23 INFO - 'talos_options': ['--disable-e10s', 13:27:23 INFO - '--xperf_path', 13:27:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:27:23 INFO - 'tests': ['tp5n']}, 13:27:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:27:23 INFO - 'talos_options': ['--xperf_path', 13:27:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:27:23 INFO - 'tests': ['tp5n']}}, 13:27:23 INFO - 'talos.zip': {'path': '', 13:27:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:27:23 INFO - Running post-action listener: _resource_record_post_action 13:27:23 INFO - [mozharness: 2017-01-07 21:27:23.274650Z] Finished populate-webroot step (success) 13:27:23 INFO - [mozharness: 2017-01-07 21:27:23.274743Z] Running create-virtualenv step. 13:27:23 INFO - Running pre-action listener: _resource_record_pre_action 13:27:23 INFO - Running main action method: create_virtualenv 13:27:23 INFO - Creating virtualenv /builds/slave/test/build/venv 13:27:23 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 13:27:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:27:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:27:23 INFO - Using real prefix '/usr' 13:27:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:27:23 INFO - Installing distribute.............................................................................................................................................................................................done. 13:27:25 INFO - Installing pip.................done. 13:27:25 INFO - Return code: 0 13:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:27:26 INFO - Reading from file tmpfile_stdout 13:27:26 INFO - Output received: 13:27:26 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) 13:27:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:26 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:26 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 13:27:26 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 13:27:26 INFO - Using env: {'DISPLAY': ':0', 13:27:26 INFO - 'HOME': '/home/cltbld', 13:27:26 INFO - 'LANG': 'en_US.UTF-8', 13:27:26 INFO - 'LANGUAGE': 'en_US:en', 13:27:26 INFO - 'LOGNAME': 'cltbld', 13:27:26 INFO - 'MAIL': '/var/mail/cltbld', 13:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:26 INFO - 'MOZ_NO_REMOTE': '1', 13:27:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:26 INFO - 'NO_EM_RESTART': '1', 13:27:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:26 INFO - 'PWD': '/builds/slave/test', 13:27:26 INFO - 'SHELL': '/bin/bash', 13:27:26 INFO - 'SHLVL': '1', 13:27:26 INFO - 'TERM': 'linux', 13:27:26 INFO - 'TMOUT': '86400', 13:27:26 INFO - 'USER': 'cltbld', 13:27:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:26 INFO - '_': '/tools/buildbot/bin/python'} 13:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:26 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 13:27:26 INFO - Cleaning up... 13:27:26 INFO - Return code: 0 13:27:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:26 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:26 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 13:27:26 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 13:27:26 INFO - Using env: {'DISPLAY': ':0', 13:27:26 INFO - 'HOME': '/home/cltbld', 13:27:26 INFO - 'LANG': 'en_US.UTF-8', 13:27:26 INFO - 'LANGUAGE': 'en_US:en', 13:27:26 INFO - 'LOGNAME': 'cltbld', 13:27:26 INFO - 'MAIL': '/var/mail/cltbld', 13:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:26 INFO - 'MOZ_NO_REMOTE': '1', 13:27:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:26 INFO - 'NO_EM_RESTART': '1', 13:27:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:26 INFO - 'PWD': '/builds/slave/test', 13:27:26 INFO - 'SHELL': '/bin/bash', 13:27:26 INFO - 'SHLVL': '1', 13:27:26 INFO - 'TERM': 'linux', 13:27:26 INFO - 'TMOUT': '86400', 13:27:26 INFO - 'USER': 'cltbld', 13:27:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:26 INFO - '_': '/tools/buildbot/bin/python'} 13:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:26 INFO - Downloading/unpacking psutil>=3.1.1 13:27:26 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 13:27:26 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 13:27:26 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 13:27:26 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 13:27:26 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 13:27:26 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 13:27:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:27:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:27:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:27:28 INFO - Installing collected packages: psutil 13:27:28 INFO - Running setup.py install for psutil 13:27:28 INFO - building 'psutil._psutil_linux' extension 13:27:28 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 13:27:28 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 13:27:28 INFO - building 'psutil._psutil_posix' extension 13:27:28 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 13:27:28 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 13:27:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:27:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:27:29 INFO - Successfully installed psutil 13:27:29 INFO - Cleaning up... 13:27:29 INFO - Return code: 0 13:27:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:27:29 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')]} 13:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:29 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')]} 13:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:29 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:29 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 13:27:29 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 13:27:29 INFO - Using env: {'DISPLAY': ':0', 13:27:29 INFO - 'HOME': '/home/cltbld', 13:27:29 INFO - 'LANG': 'en_US.UTF-8', 13:27:29 INFO - 'LANGUAGE': 'en_US:en', 13:27:29 INFO - 'LOGNAME': 'cltbld', 13:27:29 INFO - 'MAIL': '/var/mail/cltbld', 13:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:29 INFO - 'MOZ_NO_REMOTE': '1', 13:27:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:29 INFO - 'NO_EM_RESTART': '1', 13:27:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:29 INFO - 'PWD': '/builds/slave/test', 13:27:29 INFO - 'SHELL': '/bin/bash', 13:27:29 INFO - 'SHLVL': '1', 13:27:29 INFO - 'TERM': 'linux', 13:27:29 INFO - 'TMOUT': '86400', 13:27:29 INFO - 'USER': 'cltbld', 13:27:29 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:29 INFO - '_': '/tools/buildbot/bin/python'} 13:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:27:29 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 13:27:29 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 13:27:29 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 13:27:29 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 13:27:29 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 13:27:29 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 13:27:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:27:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:27:31 INFO - Installing collected packages: mozsystemmonitor 13:27:31 INFO - Running setup.py install for mozsystemmonitor 13:27:31 INFO - Successfully installed mozsystemmonitor 13:27:31 INFO - Cleaning up... 13:27:31 INFO - Return code: 0 13:27:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:27:31 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')]} 13:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:31 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')]} 13:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:31 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:31 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 13:27:31 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 13:27:31 INFO - Using env: {'DISPLAY': ':0', 13:27:31 INFO - 'HOME': '/home/cltbld', 13:27:31 INFO - 'LANG': 'en_US.UTF-8', 13:27:31 INFO - 'LANGUAGE': 'en_US:en', 13:27:31 INFO - 'LOGNAME': 'cltbld', 13:27:31 INFO - 'MAIL': '/var/mail/cltbld', 13:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:31 INFO - 'MOZ_NO_REMOTE': '1', 13:27:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:31 INFO - 'NO_EM_RESTART': '1', 13:27:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:31 INFO - 'PWD': '/builds/slave/test', 13:27:31 INFO - 'SHELL': '/bin/bash', 13:27:31 INFO - 'SHLVL': '1', 13:27:31 INFO - 'TERM': 'linux', 13:27:31 INFO - 'TMOUT': '86400', 13:27:31 INFO - 'USER': 'cltbld', 13:27:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:31 INFO - '_': '/tools/buildbot/bin/python'} 13:27:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:31 INFO - Downloading/unpacking jsonschema==2.5.1 13:27:31 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 13:27:31 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 13:27:31 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 13:27:31 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 13:27:31 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 13:27:31 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 13:27:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:27:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:27:33 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 13:27:33 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 13:27:33 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 13:27:33 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 13:27:33 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 13:27:33 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 13:27:34 INFO - Downloading functools32-3.2.3-2.tar.gz 13:27:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:27:34 INFO - warning: no files found matching '*.txt' 13:27:34 INFO - no previously-included directories found matching 'build' 13:27:34 INFO - no previously-included directories found matching 'dist' 13:27:34 INFO - no previously-included directories found matching '.git*' 13:27:34 INFO - Installing collected packages: jsonschema, functools32 13:27:34 INFO - Running setup.py install for functools32 13:27:34 INFO - warning: no files found matching '*.txt' 13:27:34 INFO - no previously-included directories found matching 'build' 13:27:34 INFO - no previously-included directories found matching 'dist' 13:27:34 INFO - no previously-included directories found matching '.git*' 13:27:34 INFO - Successfully installed jsonschema functools32 13:27:34 INFO - Cleaning up... 13:27:34 INFO - Return code: 0 13:27:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:27:34 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')]} 13:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:34 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')]} 13:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:34 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:34 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 13:27:34 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 13:27:34 INFO - Using env: {'DISPLAY': ':0', 13:27:34 INFO - 'HOME': '/home/cltbld', 13:27:34 INFO - 'LANG': 'en_US.UTF-8', 13:27:34 INFO - 'LANGUAGE': 'en_US:en', 13:27:34 INFO - 'LOGNAME': 'cltbld', 13:27:34 INFO - 'MAIL': '/var/mail/cltbld', 13:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:34 INFO - 'MOZ_NO_REMOTE': '1', 13:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:34 INFO - 'NO_EM_RESTART': '1', 13:27:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:34 INFO - 'PWD': '/builds/slave/test', 13:27:34 INFO - 'SHELL': '/bin/bash', 13:27:34 INFO - 'SHLVL': '1', 13:27:34 INFO - 'TERM': 'linux', 13:27:34 INFO - 'TMOUT': '86400', 13:27:34 INFO - 'USER': 'cltbld', 13:27:34 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:34 INFO - '_': '/tools/buildbot/bin/python'} 13:27:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:27:34 INFO - Cleaning up... 13:27:34 INFO - Return code: 0 13:27:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:27:34 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')]} 13:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:34 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')]} 13:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:34 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:34 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 13:27:34 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 13:27:34 INFO - Using env: {'DISPLAY': ':0', 13:27:34 INFO - 'HOME': '/home/cltbld', 13:27:34 INFO - 'LANG': 'en_US.UTF-8', 13:27:34 INFO - 'LANGUAGE': 'en_US:en', 13:27:34 INFO - 'LOGNAME': 'cltbld', 13:27:34 INFO - 'MAIL': '/var/mail/cltbld', 13:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:34 INFO - 'MOZ_NO_REMOTE': '1', 13:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:34 INFO - 'NO_EM_RESTART': '1', 13:27:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:34 INFO - 'PWD': '/builds/slave/test', 13:27:34 INFO - 'SHELL': '/bin/bash', 13:27:34 INFO - 'SHLVL': '1', 13:27:34 INFO - 'TERM': 'linux', 13:27:34 INFO - 'TMOUT': '86400', 13:27:34 INFO - 'USER': 'cltbld', 13:27:34 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:34 INFO - '_': '/tools/buildbot/bin/python'} 13:27:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:34 INFO - Downloading/unpacking blobuploader==1.2.4 13:27:34 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 13:27:34 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 13:27:34 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 13:27:34 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 13:27:34 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 13:27:34 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 13:27:36 INFO - Downloading blobuploader-1.2.4.tar.gz 13:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:27:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:27:36 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 13:27:36 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 13:27:36 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 13:27:36 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 13:27:36 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 13:27:36 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 13:27:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:27:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:27:37 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 13:27:37 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 13:27:37 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 13:27:37 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 13:27:37 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 13:27:37 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 13:27:37 INFO - Downloading docopt-0.6.1.tar.gz 13:27:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:27:37 INFO - Installing collected packages: blobuploader, requests, docopt 13:27:37 INFO - Running setup.py install for blobuploader 13:27:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:27:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:27:37 INFO - Running setup.py install for requests 13:27:37 INFO - Running setup.py install for docopt 13:27:38 INFO - Successfully installed blobuploader requests docopt 13:27:38 INFO - Cleaning up... 13:27:38 INFO - Return code: 0 13:27:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:38 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')]} 13:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:38 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')]} 13:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:38 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:38 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 13:27:38 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 13:27:38 INFO - Using env: {'DISPLAY': ':0', 13:27:38 INFO - 'HOME': '/home/cltbld', 13:27:38 INFO - 'LANG': 'en_US.UTF-8', 13:27:38 INFO - 'LANGUAGE': 'en_US:en', 13:27:38 INFO - 'LOGNAME': 'cltbld', 13:27:38 INFO - 'MAIL': '/var/mail/cltbld', 13:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:38 INFO - 'MOZ_NO_REMOTE': '1', 13:27:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:38 INFO - 'NO_EM_RESTART': '1', 13:27:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:38 INFO - 'PWD': '/builds/slave/test', 13:27:38 INFO - 'SHELL': '/bin/bash', 13:27:38 INFO - 'SHLVL': '1', 13:27:38 INFO - 'TERM': 'linux', 13:27:38 INFO - 'TMOUT': '86400', 13:27:38 INFO - 'USER': 'cltbld', 13:27:38 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:38 INFO - '_': '/tools/buildbot/bin/python'} 13:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:27:38 INFO - Running setup.py (path:/tmp/pip-EUCVG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:27:38 INFO - Running setup.py (path:/tmp/pip-5ltB4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:27:38 INFO - Running setup.py (path:/tmp/pip-LR4icQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:27:38 INFO - Running setup.py (path:/tmp/pip-H_uyYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:27:38 INFO - Running setup.py (path:/tmp/pip-zxbegS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:27:38 INFO - Running setup.py (path:/tmp/pip-Sxllal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:27:38 INFO - Running setup.py (path:/tmp/pip-rABC4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:27:38 INFO - Running setup.py (path:/tmp/pip-9D7SwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:27:38 INFO - Running setup.py (path:/tmp/pip-8wRq2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:27:38 INFO - Running setup.py (path:/tmp/pip-qo5opY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:27:39 INFO - Running setup.py (path:/tmp/pip-rGHGda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:27:39 INFO - Running setup.py (path:/tmp/pip-Ln441b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:27:39 INFO - Running setup.py (path:/tmp/pip-Gfhksx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:27:39 INFO - Running setup.py (path:/tmp/pip-vnig4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:39 INFO - Running setup.py (path:/tmp/pip-FPYKfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:27:39 INFO - Running setup.py (path:/tmp/pip-7t1_49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:27:39 INFO - Running setup.py (path:/tmp/pip-bmVGZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:27:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:27:39 INFO - Running setup.py install for manifestparser 13:27:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:27:39 INFO - Running setup.py install for mozcrash 13:27:39 INFO - Running setup.py install for mozdebug 13:27:39 INFO - Running setup.py install for mozdevice 13:27:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:27:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:27:39 INFO - Running setup.py install for mozfile 13:27:40 INFO - Running setup.py install for mozhttpd 13:27:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Running setup.py install for mozinfo 13:27:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Running setup.py install for mozInstall 13:27:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Running setup.py install for mozleak 13:27:40 INFO - Running setup.py install for mozlog 13:27:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Running setup.py install for moznetwork 13:27:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Running setup.py install for mozprocess 13:27:40 INFO - Running setup.py install for mozprofile 13:27:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:27:40 INFO - Running setup.py install for mozrunner 13:27:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:27:41 INFO - Running setup.py install for mozscreenshot 13:27:41 INFO - Running setup.py install for moztest 13:27:41 INFO - Running setup.py install for mozversion 13:27:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:27:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:27:41 INFO - Cleaning up... 13:27:41 INFO - Return code: 0 13:27:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:41 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')]} 13:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:41 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')]} 13:27:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:41 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:41 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 13:27:41 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 13:27:41 INFO - Using env: {'DISPLAY': ':0', 13:27:41 INFO - 'HOME': '/home/cltbld', 13:27:41 INFO - 'LANG': 'en_US.UTF-8', 13:27:41 INFO - 'LANGUAGE': 'en_US:en', 13:27:41 INFO - 'LOGNAME': 'cltbld', 13:27:41 INFO - 'MAIL': '/var/mail/cltbld', 13:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:41 INFO - 'MOZ_NO_REMOTE': '1', 13:27:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:41 INFO - 'NO_EM_RESTART': '1', 13:27:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:41 INFO - 'PWD': '/builds/slave/test', 13:27:41 INFO - 'SHELL': '/bin/bash', 13:27:41 INFO - 'SHLVL': '1', 13:27:41 INFO - 'TERM': 'linux', 13:27:41 INFO - 'TMOUT': '86400', 13:27:41 INFO - 'USER': 'cltbld', 13:27:41 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:41 INFO - '_': '/tools/buildbot/bin/python'} 13:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:27:41 INFO - Running setup.py (path:/tmp/pip-HylPMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:27:41 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)) 13:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:27:41 INFO - Running setup.py (path:/tmp/pip-mt7JKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:27:41 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)) 13:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:27:41 INFO - Running setup.py (path:/tmp/pip-4pvA7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:27:41 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)) 13:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:27:41 INFO - Running setup.py (path:/tmp/pip-L_uZWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:27:41 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)) 13:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:27:41 INFO - Running setup.py (path:/tmp/pip-AmoJp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:27:41 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)) 13:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:27:41 INFO - Running setup.py (path:/tmp/pip-t7gfni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:27:42 INFO - Running setup.py (path:/tmp/pip-fQq5XZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:27:42 INFO - Running setup.py (path:/tmp/pip-eFyMQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:27:42 INFO - Running setup.py (path:/tmp/pip-DAbKZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:27:42 INFO - Running setup.py (path:/tmp/pip-213qgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:27:42 INFO - Running setup.py (path:/tmp/pip-GNlgLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:27:42 INFO - Running setup.py (path:/tmp/pip-IrwdKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:27:42 INFO - Running setup.py (path:/tmp/pip-NtK6C5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:27:42 INFO - Running setup.py (path:/tmp/pip-OE160S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:42 INFO - Running setup.py (path:/tmp/pip-Sx32o3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:27:42 INFO - Running setup.py (path:/tmp/pip-yJT0Yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:27:42 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)) 13:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:27:42 INFO - Running setup.py (path:/tmp/pip-boXeYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:27:42 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)) 13:27:42 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)) 13:27:42 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)) 13:27:42 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)) 13:27:42 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)) 13:27:42 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)) 13:27:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:27:42 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 13:27:42 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 13:27:42 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 13:27:42 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 13:27:42 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 13:27:42 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 13:27:44 INFO - Downloading blessings-1.6.tar.gz 13:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:27:44 INFO - Installing collected packages: blessings 13:27:44 INFO - Running setup.py install for blessings 13:27:45 INFO - Successfully installed blessings 13:27:45 INFO - Cleaning up... 13:27:45 INFO - Return code: 0 13:27:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:27:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:27:45 INFO - Reading from file tmpfile_stdout 13:27:45 INFO - Current package versions: 13:27:45 INFO - argparse == 1.2.1 13:27:45 INFO - blessings == 1.6 13:27:45 INFO - blobuploader == 1.2.4 13:27:45 INFO - docopt == 0.6.1 13:27:45 INFO - functools32 == 3.2.3-2 13:27:45 INFO - jsonschema == 2.5.1 13:27:45 INFO - manifestparser == 1.1 13:27:45 INFO - mozInstall == 1.12 13:27:45 INFO - mozcrash == 1.0 13:27:45 INFO - mozdebug == 0.1 13:27:45 INFO - mozdevice == 0.49 13:27:45 INFO - mozfile == 1.2 13:27:45 INFO - mozhttpd == 0.7 13:27:45 INFO - mozinfo == 0.9 13:27:45 INFO - mozleak == 0.1 13:27:45 INFO - mozlog == 3.4 13:27:45 INFO - moznetwork == 0.27 13:27:45 INFO - mozprocess == 0.24 13:27:45 INFO - mozprofile == 0.28 13:27:45 INFO - mozrunner == 6.13 13:27:45 INFO - mozscreenshot == 0.1 13:27:45 INFO - mozsystemmonitor == 0.3 13:27:45 INFO - moztest == 0.8 13:27:45 INFO - mozversion == 1.4 13:27:45 INFO - psutil == 3.1.1 13:27:45 INFO - requests == 1.2.3 13:27:45 INFO - wsgiref == 0.1.2 13:27:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:45 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 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:45 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 13:27:45 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 13:27:45 INFO - Using env: {'DISPLAY': ':0', 13:27:45 INFO - 'HOME': '/home/cltbld', 13:27:45 INFO - 'LANG': 'en_US.UTF-8', 13:27:45 INFO - 'LANGUAGE': 'en_US:en', 13:27:45 INFO - 'LOGNAME': 'cltbld', 13:27:45 INFO - 'MAIL': '/var/mail/cltbld', 13:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:45 INFO - 'MOZ_NO_REMOTE': '1', 13:27:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:45 INFO - 'NO_EM_RESTART': '1', 13:27:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:45 INFO - 'PWD': '/builds/slave/test', 13:27:45 INFO - 'SHELL': '/bin/bash', 13:27:45 INFO - 'SHLVL': '1', 13:27:45 INFO - 'TERM': 'linux', 13:27:45 INFO - 'TMOUT': '86400', 13:27:45 INFO - 'USER': 'cltbld', 13:27:45 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:45 INFO - '_': '/tools/buildbot/bin/python'} 13:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 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)) 13:27:45 INFO - Cleaning up... 13:27:45 INFO - Return code: 0 13:27:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2281630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, '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': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', '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 13:27:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:27:45 INFO - Using env: {'DISPLAY': ':0', 13:27:45 INFO - 'HOME': '/home/cltbld', 13:27:45 INFO - 'LANG': 'en_US.UTF-8', 13:27:45 INFO - 'LANGUAGE': 'en_US:en', 13:27:45 INFO - 'LOGNAME': 'cltbld', 13:27:45 INFO - 'MAIL': '/var/mail/cltbld', 13:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:45 INFO - 'MOZ_NO_REMOTE': '1', 13:27:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:45 INFO - 'NO_EM_RESTART': '1', 13:27:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:45 INFO - 'PWD': '/builds/slave/test', 13:27:45 INFO - 'SHELL': '/bin/bash', 13:27:45 INFO - 'SHLVL': '1', 13:27:45 INFO - 'TERM': 'linux', 13:27:45 INFO - 'TMOUT': '86400', 13:27:45 INFO - 'USER': 'cltbld', 13:27:45 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:45 INFO - '_': '/tools/buildbot/bin/python'} 13:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:27:45 INFO - Cleaning up... 13:27:45 INFO - Return code: 0 13:27:45 INFO - Running post-action listener: _resource_record_post_action 13:27:45 INFO - Running post-action listener: _start_resource_monitoring 13:27:45 INFO - Starting resource monitoring. 13:27:45 INFO - [mozharness: 2017-01-07 21:27:45.844170Z] Finished create-virtualenv step (success) 13:27:45 INFO - [mozharness: 2017-01-07 21:27:45.844675Z] Running install step. 13:27:45 INFO - Running pre-action listener: _resource_record_pre_action 13:27:45 INFO - Running main action method: install 13:27:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:27:46 INFO - Reading from file tmpfile_stdout 13:27:46 INFO - Detecting whether we're running mozinstall >=1.0... 13:27:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:27:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:27:46 INFO - Reading from file tmpfile_stdout 13:27:46 INFO - Output received: 13:27:46 INFO - Usage: mozinstall [options] installer 13:27:46 INFO - Options: 13:27:46 INFO - -h, --help show this help message and exit 13:27:46 INFO - -d DEST, --destination=DEST 13:27:46 INFO - Directory to install application into. [default: 13:27:46 INFO - "/builds/slave/test"] 13:27:46 INFO - --app=APP Application being installed. [default: firefox] 13:27:46 INFO - mkdir: /builds/slave/test/build/application 13:27:46 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'] 13:27:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:28:02 INFO - Reading from file tmpfile_stdout 13:28:02 INFO - Output received: 13:28:02 INFO - /builds/slave/test/build/application/firefox/firefox 13:28:02 INFO - Running post-action listener: _resource_record_post_action 13:28:02 INFO - [mozharness: 2017-01-07 21:28:02.731270Z] Finished install step (success) 13:28:02 INFO - [mozharness: 2017-01-07 21:28:02.731546Z] Running run-tests step. 13:28:02 INFO - Running pre-action listener: _resource_record_pre_action 13:28:02 INFO - Running main action method: run_tests 13:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:28:02 INFO - Python 2.7.3 13:28:02 INFO - Return code: 0 13:28:02 INFO - grabbing minidump binary from tooltool 13:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:28:02 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 0x230a850>, '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 0x227f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227fb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:28:02 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 13:28:02 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 13:28:02 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 13:28:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:28:02 INFO - Return code: 0 13:28:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:28:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:28:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:28:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:28:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 13:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 13:28:02 INFO - Using env: {'DISPLAY': ':0', 13:28:02 INFO - 'HOME': '/home/cltbld', 13:28:02 INFO - 'LANG': 'en_US.UTF-8', 13:28:02 INFO - 'LANGUAGE': 'en_US:en', 13:28:02 INFO - 'LOGNAME': 'cltbld', 13:28:02 INFO - 'MAIL': '/var/mail/cltbld', 13:28:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:28:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:28:02 INFO - 'MOZ_NO_REMOTE': '1', 13:28:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:28:02 INFO - 'NO_EM_RESTART': '1', 13:28:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:28:02 INFO - 'PWD': '/builds/slave/test', 13:28:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:28:02 INFO - 'SHELL': '/bin/bash', 13:28:02 INFO - 'SHLVL': '1', 13:28:02 INFO - 'TERM': 'linux', 13:28:02 INFO - 'TMOUT': '86400', 13:28:02 INFO - 'USER': 'cltbld', 13:28:02 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396', 13:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:28:02 INFO - '_': '/tools/buildbot/bin/python'} 13:28:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 13:28:03 INFO - mozversion application_buildid: 20170107203214 13:28:03 INFO - mozversion application_changeset: 77eebb6c80cd5249b9a43bbcdefdaaa9d9845d1e 13:28:03 INFO - mozversion application_display_name: Nightly 13:28:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:28:03 INFO - mozversion application_name: Firefox 13:28:03 INFO - mozversion application_remotingname: firefox 13:28:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:28:03 INFO - mozversion application_vendor: Mozilla 13:28:03 INFO - mozversion application_version: 53.0a1 13:28:03 INFO - mozversion platform_buildid: 20170107203214 13:28:03 INFO - mozversion platform_changeset: 77eebb6c80cd5249b9a43bbcdefdaaa9d9845d1e 13:28:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:28:03 INFO - mozversion platform_version: 53.0a1 13:28:03 INFO - using testdate: 1483824483 13:28:03 INFO - actual date: 1483824483 13:28:03 INFO - starting webserver on 'localhost:35030' 13:28:03 INFO - SUITE-START | Running 2 tests 13:28:03 INFO - TEST-START | dromaeo_css 13:28:03 INFO - operating with platform_type : linux_ 13:28:03 INFO - Initialising browser for dromaeo_css test... 13:28:03 INFO - TEST-INFO | started process 29086 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4nSKF7/profile http://localhost:35030/getInfo.html) 13:28:03 INFO - PROCESS | 29086 | 1483824483532 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 13:28:06 INFO - PROCESS | 29086 | __metrics Screen width/height:1600/1200 13:28:06 INFO - PROCESS | 29086 | colorDepth:24 13:28:06 INFO - PROCESS | 29086 | Browser inner width/height: 1024/697 13:28:06 INFO - PROCESS | 29086 | __metrics 13:28:07 INFO - PROCESS | 29086 | 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" 13:28:07 INFO - PROCESS | 29086 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:28:08 INFO - PROCESS | 29086 | console.error: PushService: 13:28:08 INFO - PROCESS | 29086 | stateChangeProcessEnqueue: Error transitioning state 13:28:08 INFO - PROCESS | 29086 | UnknownError 13:28:08 INFO - TEST-INFO | 29086: exit 0 13:28:08 INFO - Browser initialized. 13:28:08 INFO - Running cycle 1/1 for dromaeo_css test... 13:28:08 INFO - TEST-INFO | started process 29166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4nSKF7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:28:21 INFO - PROCESS | 29166 | RSS: Main: 152485888 13:28:21 INFO - PROCESS | 29166 | 13:31:26 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:35030/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35030/tests/dromaeo/cssquery-ext.html) 13:31:27 INFO - PROCESS | 29166 | RSS: Main: 280178688 13:31:27 INFO - PROCESS | 29166 | 13:33:10 INFO - PROCESS | 29166 | ************************* 13:33:10 INFO - PROCESS | 29166 | A coding exception was thrown and uncaught in a Task. 13:33:10 INFO - PROCESS | 29166 | 13:33:10 INFO - PROCESS | 29166 | Full message: ReferenceError: fetch is not defined 13:33:10 INFO - PROCESS | 29166 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 13:33:10 INFO - PROCESS | 29166 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 13:33:10 INFO - PROCESS | 29166 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 13:33:10 INFO - PROCESS | 29166 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 13:33:10 INFO - PROCESS | 29166 | TaskImpl@resource://gre/modules/Task.jsm:277:3 13:33:10 INFO - PROCESS | 29166 | asyncFunction@resource://gre/modules/Task.jsm:252:14 13:33:10 INFO - PROCESS | 29166 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 13:33:10 INFO - PROCESS | 29166 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 13:33:10 INFO - PROCESS | 29166 | TaskImpl@resource://gre/modules/Task.jsm:277:3 13:33:10 INFO - PROCESS | 29166 | asyncFunction@resource://gre/modules/Task.jsm:252:14 13:33:10 INFO - PROCESS | 29166 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 13:33:10 INFO - PROCESS | 29166 | 13:33:10 INFO - PROCESS | 29166 | ************************* 13:34:30 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:35030/tests/dromaeo/cssquery-ext.html (next: http://localhost:35030/tests/dromaeo/cssquery-jquery.html) 13:34:30 INFO - PROCESS | 29166 | RSS: Main: 239104000 13:34:30 INFO - PROCESS | 29166 | 13:37:32 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:35030/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35030/tests/dromaeo/cssquery-mootools.html) 13:37:33 INFO - PROCESS | 29166 | RSS: Main: 187088896 13:37:33 INFO - PROCESS | 29166 | 13:40:35 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:35030/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35030/tests/dromaeo/cssquery-prototype.html) 13:40:36 INFO - PROCESS | 29166 | RSS: Main: 186138624 13:40:36 INFO - PROCESS | 29166 | 13:43:39 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:35030/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35030/tests/dromaeo/cssquery-yui.html) 13:43:40 INFO - PROCESS | 29166 | RSS: Main: 184082432 13:43:40 INFO - PROCESS | 29166 | 13:46:42 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:35030/tests/dromaeo/cssquery-yui.html (next: http://localhost:35030/tests/dromaeo/cssquery-dojo.html) 13:46:43 INFO - PROCESS | 29166 | RSS: Main: 278982656 13:46:43 INFO - PROCESS | 29166 | 13:46:43 INFO - PROCESS | 29166 | __start_tp_report 13:46:43 INFO - PROCESS | 29166 | _x_x_mozilla_page_load 13:46:43 INFO - PROCESS | 29166 | _x_x_mozilla_page_load_details 13:46:43 INFO - PROCESS | 29166 | |i|pagename|runs| 13:46:43 INFO - PROCESS | 29166 | |0;dojo.html;2492.152668092848;2540;2543.6184572314137;2574.7425257474247;2592.8055395845317;4693.249080147177;4702.177119004177;4715.6463265255115;4734.218853889112;4739.668223224376;4716.90566188676;4716.9292460613115;4776;4785.497522760113;4828.855134345975;4351.086271882893;4378.40891479651;4416.646668266531;4436.489803672571;4458.576435238663;4031.6169963853386;4479.8432054878085;4483.3723278741;4521.050579378334;4561.087782443508;4341.630961368288;4451.755153466558;4466.665000124987;4473.82104715811;4557.567031132037;4353.477582690098;4414.6688998324935;4422.823087076513;4425.7565833879125;4429.402030725875;4395.780210989437;4397.032652816375;4398.978005109954;4401.845935392246;4403.251447253974;4707.270373092176;4753.762311884392;4768.0463907218345;4793.209120495114;4851.611871050308;5149.433562308143;5154.896902061975;5158.509941555546;5160.993606246783;5211.062008838408;4956.578690811285;5190.636655434121;5197.090509160919;5199.2461093141665;5204.9219261711105;4657.976710116428;4759.833405830779;4796.928046079312;4804.111239420718;4861.854144375674;5202.2196670499725;5258.2638430619745;5261.3686357637325;5262.000219958196;5262.184361423985;4998.4251811042195;5043.672161309503;5044.243363495505;5048.798048078109;5083.440821509631;4988.7505624718615;5018.372703412074;5021.07110184617;5030.547250747451;5091.66904151229;5010.874728131753;5062.898742025139;5064.417591976896;5084.3644544431945;5103.617228707862;5038.5969122470115;5039.798408063636;5051.848444546669;5061.392632884003;5105.5149760772665;4333.696641235147;4371.519132895379;4408.074304396113;4424.269995450779;4459.063596644741;3735.084904198489;3736.645018723217;3740.8503659853845;3741.644543768338;3780.3384407728536;4385.846495372711;4387.276099443556;4387.473503179575;4418.381426600278;4424.181526417558;5028.069807085628;5030.673006254582;5037.11850426174;5038.546530812245;5069.797208111634;4379.912401751947;4384.824607015684;4397.73613583186;4444.31113177458;4479.059397526491;4580;4584.404027476474;4586.197415452282;4607.884802879955;4636.559526844944;4753.144434001774;4756.571908528249;4757.072370887644;4766.785494652819;4816.34979277807;5015.799368025238;5015.924761128659;5062.038212739568;5108.080545501845;5138.922916156186;4837.879053023562;4845.878853028703;4850.442199147144;4873.074115917964;4891.364122664031;4877.487863774394;4905.1416002200185;4905.5094490550655;5107.9489205107475;5139.922901156411;5051.772670229775;5109.233614957786;5112.3865136183895;5130.358705161855;5139.997700448376;4815.518448155157;4853.6602437829015;4913.361263035782;4950.6782059166035;4998.625103117353;4975.776090076028;4977.726225057657;4979.253112033224;5010.448850626501;5028.723420211923;4914.557689807934;4916.950830491793;4917.975410122926;4921.237208232729;4929.704217746947;4909.410870695539;4977.850664480072;5000.224965130411;5018.573421259088;5069.239614057921;4923.630727695366;4924.778384972613;4948.975255123702;4965.4041515018425;4969 13:46:43 INFO - PROCESS | 29166 | |1;ext.html;532.8534652970433;546.7840203119765;555.0203890133912;561.5227057001549;565.6097292867921;30352.84823575882;31107;35050.474242886376;36469.45295820565;36876.63123368766;43705.34441983365;43919.3412098818;43975.78012109935;44098.559014409846;44110;7767.456278060524;7946.404019698517;7947.403944704141;7952.403569732293;7954.761357159295;7659.387249020065;7786.182450842636;7848.372130229596;9951.104400603945;10298.588056477733;10253.077223049924;10310.278280520366;10312.278140530167;10312.587496500131;10356;10042.397456152654;10079.294449388546;10082;10102.494875256209;10102.848457273147;10052.597896084148;10125.848112278321;10130.594776208944;10131;10133.493325333704;39750.60249397498;39998;40050.19899601991;40089.19821603552;40103;3719.8326075326677;3767.1523907120954;3808.27642747877;3833.233353329317;3850.7497012694084;28695;28831.233753249224;29045.70954290451;29709.257268568243;29903.401931961456;3499.4051011328133;3528.5060091587197;3542.6811586957297;3572.178398968226;3698.9075273118387;239104;239136;239499;240246.99999999825;240901;54734;55051;55060.44939550553;55187;55250.44749552533;58692.7065364679;58876.41123588709;58910.41089589135;58952.41047589469;59046.40953590409;12516.311602861748;12661.74676506483;12739;12740.554080607135;12742.80885786714;8356.665733370719;8739.519326437106;8777.034526202113;8791.384603077724;8800.16398442147;12974.675633109058;17343.826561734473;17645.294188232583;17817.46547603574;18454.723179152323;6732;6786.76246331376;6855.965720171467;7059.752908648173;7102.183248926336;834.9081601023883;839.093778718982;847.9957600211959;870.3472395703222;878.3587980774071;27551;28856.134315970554;28967.42065158685;29267.853660731987;29461;968;988.6935050134481;992.4690290694443;993.1310103659298;1132.923722463663;15060.472883449245;15473.381064757283;15530.223488825739;15736.449224277096;15846.524604261891;321623;323325;323696;324987;325612;52908.206376904374;53064.73467632714;53088.469115308355;53232.467675323525;53241.73379133079;1249.9187552809037;1276.8403949506312;1282.499825068224;1289.4906511927736;1289.8065290206544;26055.869720651277;27246;27609;28028.439431210852;28258.858705707164;43295.56704432915;43712.562874372124;43751.56248437475;43824.780876095414;43885.78057109694;269938;271132;271320;272850;274210;33485.33029339351;36478.27043459063;36829.26341473209;37327;37458.438123428714;38130.809345953094;42210.78894605507;47962.76018620024;48025.75987120042;48110.5188948106;36297.27405451823;36612.633873660925;36767.26465470622;37215.25569488541;37354;37468.1367893881;37822.243555129295;38091;38393;38546.229075418894;36255.81872090622;36875.446868297535;36955.81522092372;37348.6265137356;37391;31769.205769855012;34078.65921340854;34217.31565368629;34319.656803431644;34383.828080859435;25302.620460693473;25343.493130137664;25459;25470.49059018772;26015.869920650275 13:46:43 INFO - PROCESS | 29166 | |2;jquery.html;353.82839322928373;367.5644361431704;369.25963443296195;380.11243745853466;387.1289598403592;16594.33622655094;17568.47294581163;18067.81932180678;18109.818901810977;18133.4559963201;15780.684386312267;17653.55866103348;17968.910155449204;18132.637347253047;18258.452246432596;5119.4624564420665;5268.1834315680935;5293.073712100387;5299.205119232127;5299.33758280215;5158.948410515903;5196.714309836208;5230.476952304777;5248.291480650101;5260.526552610283;5037.49625037497;5148.3564554430695;5161.741912904358;5163.7418129093585;5168.844934651947;5122.897542049157;5144.048351055067;5211.869703257411;5406.567474602022;5418.647787893814;5425.457454254543;5433.429489903582;5435.157550579666;5438;5448.91826622601;12386.690332741664;12511.436985335682;12537.623871283877;12558.874411255862;16640.835141540098;2132.1471411435396;2159.7948194921455;2220.367195349334;2271.693321401606;2304.320225533472;9019.368644194908;9214.032526584679;11323.263987840764;12874.742505149938;13352.399142038632;1786.4551311850012;1835.3667984545443;1948.6979518174705;1965.49879780655;1970.103602860695;64418.677906610166;74092.25907740908;78609.60695196487;79698.20301797024;80737.59631201865;9387.249020078378;9779.234133837148;9848.162906152915;10060.798784024279;10146.746331341776;13893.513727019506;13907.58277251684;13909.513167039106;14084.577462676136;14177.362018709182;6626.635535045619;6790.864182716318;6849.349311815369;6849.760258390932;6897.206821215625;6253.6873156342;6306.905396419057;6429.646369449725;6451.48388128958;6469.482441404676;4219.409282700424;4260.382244574519;4265.1469706058915;4271.423357846713;4320.373545835836;1774.1750086209975;1812.8005919348861;1832.6334733053443;1839.6872531669649;1846.750688657014;968.4673429613825;981.0532835813474;981.6367943860674;988.6342053440274;1004.6082028009082;16525.33898644065;16633.3346666132;16821.579460513585;17745.11274436299;17832.465026049238;954.9474778887089;962.3263715399248;970.825251454745;972.1493693018608;976.8046390721884;9048.28082664305;9414.670486533076;9650;9697.369670971364;9839.458829764431;328792;330133;330166;449357;451088;90346.54826725824;91859.54070229606;93353.53323233477;94131;94997.52501237587;983.1201075037944;1003.4430890855499;1016.2682868334787;1038.776663017455;1048.1248157788332;15303.923480382527;15366.46217382431;15759.369625214864;16219.837801622305;16420.425285114965;39456.2108757829;40418.1916361677;41127.58872411238;41156.79421602873;41369.1726165469;51864.0729130732;56915.715421422625;57268.42731572631;57570.712146439;57792.71103644455;7411.666475008746;7851.646675899712;7890.329322007694;7919.960400197962;8205.630746616536;10436.791264174826;12543.498260069495;12861.164024338388;12933.741325173256;13431.664208394868;9797.265205109503;9916;10077.899221007696;10516.894831051592;10744.19418543628;6034.909476357946;10959.232853700165;10960.28758130719;10965.396903170402;10982.780344392908;10395.86987523183;10467.267291289536;10510.842337365099;10630.574777008833;11075.113990880871;7451.143118541437;7774.766756997299;7826.452148349788;7995.680172793023;8152.755417337489;7495.737649182252;9045.638174472948;9857.802843943224;10216.795664086825;10331.896681033093 13:46:43 INFO - PROCESS | 29166 | |3;mootools.html;1198.9220700654462;1319.571139379702;1324.54965311794;1324.668832791802;1334.55959533354;6240.469560087392;6844.178698556174;6881.655917204145;6913.239543650207;7161.570305781644;7034.718611255556;7089.22018577956;7102.076730025089;7117.252688467714;7119.537230080041;1801.4055361730664;2068.420842164193;2094.1623350659706;2096.982963262816;2101.957960840782;1902.372217168338;2065.4629796253007;2111.155537784883;2112.292382052007;2131.2007997001124;2074.8236399906027;2084.3746875937236;2087.1651339464183;2087.916483340672;2090.560982193741;1976.49599352165;1992.7907569705117;1994.8404127669824;1995.2517805822818;1998.6002799440025;2011.4569066352003;2020.9090590923445;2025.9493512662154;2035.4402539301661;2049.8565100442975;7022.613756243404;7035.577865328097;7072.575645461237;7073.221945585981;7080.221175670724;4244.236037513247;4307.073979094478;4309.07354918694;4322;4324.783760811947;2699.6895357033995;2839.872205750746;2862.6421697287838;2877.4101309231555;2877.812942158753;1055.3245922609535;1084.5770149641646;1128.8814674459145;1145.8109411947019;1165.6386520178771;9044.09559044097;9095.681651142178;9098.090190980914;9115.863262051074;9300.39547429415;3788.5453745550712;4029.7985100744845;4048.7975601219823;4053.574374690674;4064.8170832312617;7801.29788319054;7861.135275119732;7861.724839630586;7864.056313242448;8011.399145064027;4262.637675797592;4322.135572885435;4323.567643235651;4328.805203765838;4514.119746649372;3631.8728844490315;3689.2805902848686;3990;3991.1419044905483;4031.596840315945;3340.981000794781;3410.4372778491766;3481.9303613927577;3517.1558825881602;3519.7712148710766;1075.4891426572315;1076.6554702495282;1083.539495714334;1100.884407137255;1107.7009207513927;781.6873250699651;804.6499772598938;805.9798505037304;815.4699445360444;818.1613845807975;4427.690061695715;4501.729896206238;4575.130725162208;4612.492625811158;4742.264948932921;477.92353223484076;482.70796168318367;485.9635527335464;486.1880659298979;490.4213028626255;2991.282092297833;3129.8748050077743;3130.89041883533;3154.8895788647287;3272.787268827519;7786.4938778979185;7789.922100778919;7891.6054197289905;7921.485103468372;7928.960355198303;7858.764237072897;7874.755694627118;7877.448578599443;7877.684892604231;8040.678372865137;6220.471259942956;6404.391582799627;6416.101745755598;6421;6475.158229430144;4135.2763266427655;4260.573942605714;4281.593248641374;4307.784610769387;4311.202427550787;7148.642567871481;7197.316254955771;7246.601436920809;7285.708571657075;7307.159676637255;7146.964265178641;7246.6376681166785;7273.599952002479;7359.742409015659;7383.150937642025;5522.088855338824;5691.800786972436;5867.20792692998;5926.940730592639;5928.199693041557;6531.967340163269;6570.310117437601;6584.703688333941;6833.965830170818;6835.897461537981;6168.629882207082;6198.380161983766;6221.906671400023;6370.267419246846;6401;5983.581149319506;6072.544559157993;6082.2701275847185;6088.8782224356155;6114.694265286629;5577.107662773937;5636.1545768133465;5636.661800291996;5645.520130788765;5652.886942261214;3953.2291203215095;3982.681385489096;3983.24318379507;4084.4894388201474;4129.566395528426;4012.9398059029722;4063.6139566741113;4098.8360465581045;4132.33882578798;4144.689148313828 13:46:43 INFO - PROCESS | 29166 | |4;prototype.html;343.9071450708309;345.73896707985466;352.3545044641523;354.7304048922818;358.6700235783079;4869;4927.901441971158;4988.850334489962;5035.924461133086;5055.205402082259;4637.791299391527;4688.53114688531;4827.758612069391;4830.396200474941;4950.381202349707;3917.5690674025836;3930.683221120928;3938.901527461822;3948.940765888514;3988.641022307999;3930.1746633206917;3941.783201923893;3943.428202910576;3944.3294639911283;3970.8610198643055;3737.7009839212933;3830.751001184914;3862.3627101528214;3893.7274390792654;3930.1943101664233;3924.548676902164;3933.6263055009726;3933.8033098344968;3934.980325098385;3944.9211015779665;3523.6300188480354;3531.1172206948263;3531.4879342495196;3546.397112490857;3548.3967725486727;4569.383133276999;4816.903661926742;4826.469088400273;4828.5412885775795;4834.299026641117;4283.678724095706;4290.2492063889;4299.204647140258;4301.634361079312;4324.978375108104;5129.256257842592;5129.820456284049;5133.409657889353;5140.845774626767;5148.3821941366905;3597.982010089933;3599.3341231872187;3615.132368231632;3616.6383361663884;3619.2942376236642;5190.558802501791;5332.466753324676;5347.315595587455;5378.924215156984;5386.973065134649;3638.472421498868;3657.652523010363;3663.6519530644546;3669.8165091745304;3685.299793039314;3703.8703645372284;3708.517892673935;3709.573399059143;3709.684676802448;3716.7769933803515;4150.460440142822;4293.44185255921;4315.935260971088;4315.978420107942;4317.978410107992;5154.139452603766;5174.14626586609;5175.17197248446;5181.5077567631015;5201.791928322825;1253.4484826676417;1256.9937150314372;1257.798752199662;1268.7018550640591;1269.5302737987004;804.7424824056246;829.5271695133837;839.3327304792582;855.6491838346249;857.9656813727381;4388.253996820548;4394.956050439519;4396.978015109968;4399.186150562156;4411.492678341968;5086.415062267886;5089;5108.618046922236;5112.182050871843;5135.380955712613;4263.76549289792;4396.582324679151;4415.911681766347;4429.3134564141965;4550.13547425988;2689.220126163391;2698.908346031841;2743.561030235153;2755.531559634867;2755.5395640310267;4775.307580400822;4792.472827988919;4802.327674125625;4825.094087835286;4831.680793219204;4991.401031876198;5196.428392876781;5203.739813009334;5213.48090039222;5225.451327610546;4796.976015119972;4800.66395352329;4809.927851082237;4811.27830825372;4832.903341933212;5064.5948324134715;5084.2627818966785;5127.974360128175;5130.6152038598;5147.459516750687;4921.188003979447;5059.038782631288;5081.669691470042;5174.353205849269;5216.686998780086;4951.405831300267;4958.925616115834;4960.859002429389;4963.875903102451;4966.950330496648;3662.6520480554304;3684.723645726635;3705.2404257126686;3706.7405281630026;3708.647678470541;5104.0812653721205;5223.895522089613;5230.424653288212;5289.523942845162;5292.708901010481;5089.236614507706;5103.285540024473;5104.744762761921;5117;5117.104506711236;5081.314022607048;5097.406328538445;5100.846974590768;5104.2088476286235;5117.43708192106;4917.508249175054;4962.801487940442;5003.949960500348;5047.2681461187185;5052.8989420210655;4888.706677599355;4986.750662466789;5054.16606259963;5178.145605974972;5184.507471790174;5048.798048078036;5067.974660126676;5068.29043933857;5070.670406423571;5079.612582086414 13:46:43 INFO - PROCESS | 29166 | |5;yui.html;623.2489849731074;647.685872351909;718.9611012087536;756.6330329790053;765.4527013185572;11553.075753939685;12328.075394345415;12337.506499740022;12342.691432714186;12351.32067736274;12142.210756300834;12172.513099476033;12183;12188.085893557974;12311;659.910912026875;673.6867356679144;776.471999040655;783.9608019599011;796.625585974591;781.890535325055;782.9451938364317;787.1262898183012;791.0230864881867;791.9960400197991;841.524538635672;843.0894633795517;843.1063073142458;843.2537204573965;843.4601854812925;689.5586824432368;704.3801454719813;839.7984483723924;839.8866153069318;840.0968958369747;598.6677394046303;612.0299325568978;613.2824595223599;615.310851845931;615.3630991923338;12929.676758081028;12947.48210071596;12985;12990.350482475838;13004.024698147678;11655.883441165564;11688.590899318484;11857.762844743056;12099;12278.912584860595;11389.430528473544;11567.479463424166;11585.246958947642;11595.884041159648;11772.234804737665;3629.328574213779;3654.14127679995;3670.210904655485;3719.9070023249637;3733.962660373416;58998.922344223975;59961.99999999956;60175;60690;62169.37830621681;10156.39061656303;10166.68495446348;10228.335158214692;10229.079382855578;10422;17593.29626814913;17613;17624.471265862045;17649.647007060044;17786.37747678825;6115.205023347025;6127.724252408652;6166.352532984062;6252.812415627538;6286.465650419674;3167.382360439738;3216.951745723816;3241.7244534214856;3287.391832510993;3335.71646410053;2782;2817.29567608098;2839.758620517238;2844.033028770228;2850.529662605647;477.2030708716452;481.2108142646063;482.6910777102657;483.95402436769155;487.3712910345685;549.8432946610197;553.3166539323931;554.1964151979646;568.7980766827717;572.9799457018985;8063.758087257392;8536.359773016924;8589.484630922165;8825.558722063872;8876.911230887737;564.3340857787849;575.2349375330883;581.4040608376448;588.808637192914;606.5693357716071;3916.2559113768293;4001.4598029265676;4095.774732389747;4137.441445404832;4179.2477354076555;63368.68315658484;63737.36262637407;64056;65545.34454655487;65677;54781.178282325796;55527.44472555303;56436.71781641065;57329;58250.12624810631;51347.36064968614;51735.48264517382;51799;52676.73661631667;53800.19299710508;7838.764837054898;7999.280064794197;8084.110747817853;8098.433109682265;8148.5518296494265;36040.27919441649;36211.63788362188;36922.26155476929;37104.628953711195;37356.06609834667;40208.396874046324;40508;42249.366259505514;44247.336289955034;45028.32457513074;11114.888851111386;11228.214025018171;11359.327918903173;11446.82829757571;12102.334371609646;12780.36098195068;12917.677058073623;13149.802752958522;13290.667733306746;13449.596512104652;12178.756424871275;12311;12481.812772808596;12550;13003.934980325037;12138.332391718188;12158.75682486363;12406.503739850667;12788.488460461478;12946.352682366032;11715.414229288672;11732.94133529327;11767.588134415255;12061.276323420623;12124.969377602649;9754.219662427131;10052.296339256181;10078.647247346307;10130.949345253226;10478.476076196312;10170.288079834341;10417.895821041995;10478.37129772216;10478.63324783629;10489.423081730578 13:46:43 INFO - PROCESS | 29166 | __end_tp_report 13:46:43 INFO - PROCESS | 29166 | __start_cc_report 13:46:43 INFO - PROCESS | 29166 | _x_x_mozilla_cycle_collect,1312 13:46:43 INFO - PROCESS | 29166 | __end_cc_report 13:46:43 INFO - PROCESS | 29166 | __startTimestamp1483825603191__endTimestamp 13:46:43 INFO - PROCESS | 29166 | 13:46:43 INFO - PROCESS | 29166 | ------- Summary: start ------- 13:46:43 INFO - PROCESS | 29166 | Number of tests: 6 13:46:43 INFO - PROCESS | 29166 | 13:46:43 INFO - PROCESS | 29166 | [#0] dojo.html Cycles:165 Average:4715.04 Median:4851.61 stddev:505.51 (10.4%) stddev-sans-first:476.03 13:46:43 INFO - PROCESS | 29166 | Values: 2492.2 2540.0 2543.6 2574.7 2592.8 4693.2 4702.2 4715.6 4734.2 4739.7 4716.9 4716.9 4776.0 4785.5 4828.9 4351.1 4378.4 4416.6 4436.5 4458.6 4031.6 4479.8 4483.4 4521.1 4561.1 4341.6 4451.8 4466.7 4473.8 4557.6 4353.5 4414.7 4422.8 4425.8 4429.4 4395.8 4397.0 4399.0 4401.8 4403.3 4707.3 4753.8 4768.0 4793.2 4851.6 5149.4 5154.9 5158.5 5161.0 5211.1 4956.6 5190.6 5197.1 5199.2 5204.9 4658.0 4759.8 4796.9 4804.1 4861.9 5202.2 5258.3 5261.4 5262.0 5262.2 4998.4 5043.7 5044.2 5048.8 5083.4 4988.8 5018.4 5021.1 5030.5 5091.7 5010.9 5062.9 5064.4 5084.4 5103.6 5038.6 5039.8 5051.8 5061.4 5105.5 4333.7 4371.5 4408.1 4424.3 4459.1 3735.1 3736.6 3740.9 3741.6 3780.3 4385.8 4387.3 4387.5 4418.4 4424.2 5028.1 5030.7 5037.1 5038.5 5069.8 4379.9 4384.8 4397.7 4444.3 4479.1 4580.0 4584.4 4586.2 4607.9 4636.6 4753.1 4756.6 4757.1 4766.8 4816.3 5015.8 5015.9 5062.0 5108.1 5138.9 4837.9 4845.9 4850.4 4873.1 4891.4 4877.5 4905.1 4905.5 5107.9 5139.9 5051.8 5109.2 5112.4 5130.4 5140.0 4815.5 4853.7 4913.4 4950.7 4998.6 4975.8 4977.7 4979.3 5010.4 5028.7 4914.6 4917.0 4918.0 4921.2 4929.7 4909.4 4977.9 5000.2 5018.6 5069.2 4923.6 4924.8 4949.0 4965.4 4969.0 13:46:43 INFO - PROCESS | 29166 | 13:46:43 INFO - PROCESS | 29166 | [#1] ext.html Cycles:180 Average:45025.02 Median:36798.26 stddev:73289.36 (199.2%) stddev-sans-first:73418.82 13:46:43 INFO - PROCESS | 29166 | Values: 532.9 546.8 555.0 561.5 565.6 30352.8 31107.0 35050.5 36469.5 36876.6 43705.3 43919.3 43975.8 44098.6 44110.0 7767.5 7946.4 7947.4 7952.4 7954.8 7659.4 7786.2 7848.4 9951.1 10298.6 10253.1 10310.3 10312.3 10312.6 10356.0 10042.4 10079.3 10082.0 10102.5 10102.8 10052.6 10125.8 10130.6 10131.0 10133.5 39750.6 39998.0 40050.2 40089.2 40103.0 3719.8 3767.2 3808.3 3833.2 3850.7 28695.0 28831.2 29045.7 29709.3 29903.4 3499.4 3528.5 3542.7 3572.2 3698.9 239104.0 239136.0 239499.0 240247.0 240901.0 54734.0 55051.0 55060.4 55187.0 55250.4 58692.7 58876.4 58910.4 58952.4 59046.4 12516.3 12661.7 12739.0 12740.6 12742.8 8356.7 8739.5 8777.0 8791.4 8800.2 12974.7 17343.8 17645.3 17817.5 18454.7 6732.0 6786.8 6856.0 7059.8 7102.2 834.9 839.1 848.0 870.3 878.4 27551.0 28856.1 28967.4 29267.9 29461.0 968.0 988.7 992.5 993.1 1132.9 15060.5 15473.4 15530.2 15736.4 15846.5 321623.0 323325.0 323696.0 324987.0 325612.0 52908.2 53064.7 53088.5 53232.5 53241.7 1249.9 1276.8 1282.5 1289.5 1289.8 26055.9 27246.0 27609.0 28028.4 28258.9 43295.6 43712.6 43751.6 43824.8 43885.8 269938.0 271132.0 271320.0 272850.0 274210.0 33485.3 36478.3 36829.3 37327.0 37458.4 38130.8 42210.8 47962.8 48025.8 48110.5 36297.3 36612.6 36767.3 37215.3 37354.0 37468.1 37822.2 38091.0 38393.0 38546.2 36255.8 36875.4 36955.8 37348.6 37391.0 31769.2 34078.7 34217.3 34319.7 34383.8 25302.6 25343.5 25459.0 25470.5 26015.9 13:46:43 INFO - PROCESS | 29166 | 13:46:43 INFO - PROCESS | 29166 | [#2] jquery.html Cycles:180 Average:24743.11 Median:4295.90 stddev:63920.87 (1488.0%) stddev-sans-first:64073.95 13:46:43 INFO - PROCESS | 29166 | Values: 353.8 367.6 369.3 380.1 387.1 16594.3 17568.5 18067.8 18109.8 18133.5 15780.7 17653.6 17968.9 18132.6 18258.5 5119.5 5268.2 5293.1 5299.2 5299.3 5158.9 5196.7 5230.5 5248.3 5260.5 5037.5 5148.4 5161.7 5163.7 5168.8 5122.9 5144.0 5211.9 5406.6 5418.6 5425.5 5433.4 5435.2 5438.0 5448.9 12386.7 12511.4 12537.6 12558.9 16640.8 2132.1 2159.8 2220.4 2271.7 2304.3 9019.4 9214.0 11323.3 12874.7 13352.4 1786.5 1835.4 1948.7 1965.5 1970.1 64418.7 74092.3 78609.6 79698.2 80737.6 9387.2 9779.2 9848.2 10060.8 10146.7 13893.5 13907.6 13909.5 14084.6 14177.4 6626.6 6790.9 6849.3 6849.8 6897.2 6253.7 6306.9 6429.6 6451.5 6469.5 4219.4 4260.4 4265.1 4271.4 4320.4 1774.2 1812.8 1832.6 1839.7 1846.8 968.5 981.1 981.6 988.6 1004.6 16525.3 16633.3 16821.6 17745.1 17832.5 954.9 962.3 970.8 972.1 976.8 9048.3 9414.7 9650.0 9697.4 9839.5 328792.0 330133.0 330166.0 449357.0 451088.0 90346.5 91859.5 93353.5 94131.0 94997.5 983.1 1003.4 1016.3 1038.8 1048.1 15303.9 15366.5 15759.4 16219.8 16420.4 39456.2 40418.2 41127.6 41156.8 41369.2 51864.1 56915.7 57268.4 57570.7 57792.7 7411.7 7851.6 7890.3 7920.0 8205.6 10436.8 12543.5 12861.2 12933.7 13431.7 9797.3 9916.0 10077.9 10516.9 10744.2 6034.9 10959.2 10960.3 10965.4 10982.8 10395.9 10467.3 10510.8 10630.6 11075.1 7451.1 7774.8 7826.5 7995.7 8152.8 7495.7 9045.6 9857.8 10216.8 10331.9 13:46:43 INFO - PROCESS | 29166 | 13:46:43 INFO - PROCESS | 29166 | [#3] mootools.html Cycles:180 Average:4526.56 Median:4507.92 stddev:2404.30 (53.3%) stddev-sans-first:2398.03 13:46:43 INFO - PROCESS | 29166 | Values: 1198.9 1319.6 1324.5 1324.7 1334.6 6240.5 6844.2 6881.7 6913.2 7161.6 7034.7 7089.2 7102.1 7117.3 7119.5 1801.4 2068.4 2094.2 2097.0 2102.0 1902.4 2065.5 2111.2 2112.3 2131.2 2074.8 2084.4 2087.2 2087.9 2090.6 1976.5 1992.8 1994.8 1995.3 1998.6 2011.5 2020.9 2025.9 2035.4 2049.9 7022.6 7035.6 7072.6 7073.2 7080.2 4244.2 4307.1 4309.1 4322.0 4324.8 2699.7 2839.9 2862.6 2877.4 2877.8 1055.3 1084.6 1128.9 1145.8 1165.6 9044.1 9095.7 9098.1 9115.9 9300.4 3788.5 4029.8 4048.8 4053.6 4064.8 7801.3 7861.1 7861.7 7864.1 8011.4 4262.6 4322.1 4323.6 4328.8 4514.1 3631.9 3689.3 3990.0 3991.1 4031.6 3341.0 3410.4 3481.9 3517.2 3519.8 1075.5 1076.7 1083.5 1100.9 1107.7 781.7 804.6 806.0 815.5 818.2 4427.7 4501.7 4575.1 4612.5 4742.3 477.9 482.7 486.0 486.2 490.4 2991.3 3129.9 3130.9 3154.9 3272.8 7786.5 7789.9 7891.6 7921.5 7929.0 7858.8 7874.8 7877.4 7877.7 8040.7 6220.5 6404.4 6416.1 6421.0 6475.2 4135.3 4260.6 4281.6 4307.8 4311.2 7148.6 7197.3 7246.6 7285.7 7307.2 7147.0 7246.6 7273.6 7359.7 7383.2 5522.1 5691.8 5867.2 5926.9 5928.2 6532.0 6570.3 6584.7 6834.0 6835.9 6168.6 6198.4 6221.9 6370.3 6401.0 5983.6 6072.5 6082.3 6088.9 6114.7 5577.1 5636.2 5636.7 5645.5 5652.9 3953.2 3982.7 3983.2 4084.5 4129.6 4012.9 4063.6 4098.8 4132.3 4144.7 13:46:43 INFO - PROCESS | 29166 | 13:46:43 INFO - PROCESS | 29166 | [#4] prototype.html Cycles:180 Average:4228.90 Median:4821.00 stddev:1215.23 (25.2%) stddev-sans-first:1183.14 13:46:43 INFO - PROCESS | 29166 | Values: 343.9 345.7 352.4 354.7 358.7 4869.0 4927.9 4988.9 5035.9 5055.2 4637.8 4688.5 4827.8 4830.4 4950.4 3917.6 3930.7 3938.9 3948.9 3988.6 3930.2 3941.8 3943.4 3944.3 3970.9 3737.7 3830.8 3862.4 3893.7 3930.2 3924.5 3933.6 3933.8 3935.0 3944.9 3523.6 3531.1 3531.5 3546.4 3548.4 4569.4 4816.9 4826.5 4828.5 4834.3 4283.7 4290.2 4299.2 4301.6 4325.0 5129.3 5129.8 5133.4 5140.8 5148.4 3598.0 3599.3 3615.1 3616.6 3619.3 5190.6 5332.5 5347.3 5378.9 5387.0 3638.5 3657.7 3663.7 3669.8 3685.3 3703.9 3708.5 3709.6 3709.7 3716.8 4150.5 4293.4 4315.9 4316.0 4318.0 5154.1 5174.1 5175.2 5181.5 5201.8 1253.4 1257.0 1257.8 1268.7 1269.5 804.7 829.5 839.3 855.6 858.0 4388.3 4395.0 4397.0 4399.2 4411.5 5086.4 5089.0 5108.6 5112.2 5135.4 4263.8 4396.6 4415.9 4429.3 4550.1 2689.2 2698.9 2743.6 2755.5 2755.5 4775.3 4792.5 4802.3 4825.1 4831.7 4991.4 5196.4 5203.7 5213.5 5225.5 4797.0 4800.7 4809.9 4811.3 4832.9 5064.6 5084.3 5128.0 5130.6 5147.5 4921.2 5059.0 5081.7 5174.4 5216.7 4951.4 4958.9 4960.9 4963.9 4967.0 3662.7 3684.7 3705.2 3706.7 3708.6 5104.1 5223.9 5230.4 5289.5 5292.7 5089.2 5103.3 5104.7 5117.0 5117.1 5081.3 5097.4 5100.8 5104.2 5117.4 4917.5 4962.8 5003.9 5047.3 5052.9 4888.7 4986.8 5054.2 5178.1 5184.5 5048.8 5068.0 5068.3 5070.7 5079.6 13:46:43 INFO - PROCESS | 29166 | 13:46:43 INFO - PROCESS | 29166 | [#5] yui.html Cycles:180 Average:14616.55 Median:22104.93 stddev:17960.99 (81.3%) stddev-sans-first:17980.63 13:46:43 INFO - PROCESS | 29166 | Values: 623.2 647.7 719.0 756.6 765.5 11553.1 12328.1 12337.5 12342.7 12351.3 12142.2 12172.5 12183.0 12188.1 12311.0 659.9 673.7 776.5 784.0 796.6 781.9 782.9 787.1 791.0 792.0 841.5 843.1 843.1 843.3 843.5 689.6 704.4 839.8 839.9 840.1 598.7 612.0 613.3 615.3 615.4 12929.7 12947.5 12985.0 12990.4 13004.0 11655.9 11688.6 11857.8 12099.0 12278.9 11389.4 11567.5 11585.2 11595.9 11772.2 3629.3 3654.1 3670.2 3719.9 3734.0 58998.9 59962.0 60175.0 60690.0 62169.4 10156.4 10166.7 10228.3 10229.1 10422.0 17593.3 17613.0 17624.5 17649.6 17786.4 6115.2 6127.7 6166.4 6252.8 6286.5 3167.4 3217.0 3241.7 3287.4 3335.7 2782.0 2817.3 2839.8 2844.0 2850.5 477.2 481.2 482.7 484.0 487.4 549.8 553.3 554.2 568.8 573.0 8063.8 8536.4 8589.5 8825.6 8876.9 564.3 575.2 581.4 588.8 606.6 3916.3 4001.5 4095.8 4137.4 4179.2 63368.7 63737.4 64056.0 65545.3 65677.0 54781.2 55527.4 56436.7 57329.0 58250.1 51347.4 51735.5 51799.0 52676.7 53800.2 7838.8 7999.3 8084.1 8098.4 8148.6 36040.3 36211.6 36922.3 37104.6 37356.1 40208.4 40508.0 42249.4 44247.3 45028.3 11114.9 11228.2 11359.3 11446.8 12102.3 12780.4 12917.7 13149.8 13290.7 13449.6 12178.8 12311.0 12481.8 12550.0 13003.9 12138.3 12158.8 12406.5 12788.5 12946.4 11715.4 11732.9 11767.6 12061.3 12125.0 9754.2 10052.3 10078.6 10130.9 10478.5 10170.3 10417.9 10478.4 10478.6 10489.4 13:46:43 INFO - PROCESS | 29166 | -------- Summary: end -------- 13:46:43 INFO - PROCESS | 29166 | 13:46:44 INFO - TEST-INFO | 29166: exit 0 13:46:44 INFO - TEST-OK | dromaeo_css | took 1121010ms 13:46:44 INFO - TEST-START | kraken 13:46:44 INFO - operating with platform_type : linux_ 13:46:44 INFO - Initialising browser for kraken test... 13:46:44 INFO - TEST-INFO | started process 29462 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC6IcKS/profile http://localhost:35030/getInfo.html) 13:46:44 INFO - PROCESS | 29462 | 1483825604530 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 13:46:47 INFO - PROCESS | 29462 | __metrics Screen width/height:1600/1200 13:46:47 INFO - PROCESS | 29462 | colorDepth:24 13:46:47 INFO - PROCESS | 29462 | Browser inner width/height: 1024/697 13:46:47 INFO - PROCESS | 29462 | __metrics 13:46:48 INFO - PROCESS | 29462 | 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" 13:46:48 INFO - PROCESS | 29462 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:46:49 INFO - PROCESS | 29462 | console.error: PushService: 13:46:49 INFO - PROCESS | 29462 | stateChangeProcessEnqueue: Error transitioning state 13:46:49 INFO - PROCESS | 29462 | UnknownError 13:46:49 INFO - TEST-INFO | 29462: exit 0 13:46:49 INFO - Browser initialized. 13:46:49 INFO - Running cycle 1/1 for kraken test... 13:46:49 INFO - TEST-INFO | started process 29548 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC6IcKS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:47:01 INFO - PROCESS | 29548 | RSS: Main: 151699456 13:47:01 INFO - PROCESS | 29548 | 13:47:04 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35030/tests/kraken/driver.html?testName=audio-beat-detection) 13:47:05 INFO - PROCESS | 29548 | RSS: Main: 206270464 13:47:05 INFO - PROCESS | 29548 | 13:47:08 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35030/tests/kraken/driver.html?testName=audio-dft) 13:47:08 INFO - PROCESS | 29548 | RSS: Main: 185323520 13:47:08 INFO - PROCESS | 29548 | 13:47:11 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35030/tests/kraken/driver.html?testName=audio-fft) 13:47:12 INFO - PROCESS | 29548 | RSS: Main: 268374016 13:47:12 INFO - PROCESS | 29548 | 13:47:14 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35030/tests/kraken/driver.html?testName=audio-oscillator) 13:47:15 INFO - PROCESS | 29548 | RSS: Main: 179527680 13:47:15 INFO - PROCESS | 29548 | 13:47:17 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35030/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:47:18 INFO - PROCESS | 29548 | RSS: Main: 182251520 13:47:18 INFO - PROCESS | 29548 | 13:47:21 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35030/tests/kraken/driver.html?testName=imaging-darkroom) 13:47:21 INFO - PROCESS | 29548 | RSS: Main: 222040064 13:47:21 INFO - PROCESS | 29548 | 13:47:25 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35030/tests/kraken/driver.html?testName=imaging-desaturate) 13:47:25 INFO - PROCESS | 29548 | RSS: Main: 216051712 13:47:25 INFO - PROCESS | 29548 | 13:47:28 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35030/tests/kraken/driver.html?testName=json-parse-financial) 13:47:29 INFO - PROCESS | 29548 | RSS: Main: 217677824 13:47:29 INFO - PROCESS | 29548 | 13:47:31 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35030/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:47:31 INFO - PROCESS | 29548 | RSS: Main: 181530624 13:47:31 INFO - PROCESS | 29548 | 13:47:33 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35030/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:47:34 INFO - PROCESS | 29548 | RSS: Main: 189227008 13:47:34 INFO - PROCESS | 29548 | 13:47:36 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35030/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:47:37 INFO - PROCESS | 29548 | RSS: Main: 197177344 13:47:37 INFO - PROCESS | 29548 | 13:47:39 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35030/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:47:40 INFO - PROCESS | 29548 | RSS: Main: 201535488 13:47:40 INFO - PROCESS | 29548 | 13:47:43 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35030/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:47:44 INFO - PROCESS | 29548 | RSS: Main: 202809344 13:47:44 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:35030/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35030/tests/kraken/driver.html?testName=ai-astar) 13:47:46 INFO - PROCESS | 29548 | RSS: Main: 254201856 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | __start_tp_report 13:47:46 INFO - PROCESS | 29548 | _x_x_mozilla_page_load 13:47:46 INFO - PROCESS | 29548 | _x_x_mozilla_page_load_details 13:47:46 INFO - PROCESS | 29548 | |i|pagename|runs| 13:47:46 INFO - PROCESS | 29548 | |0;ai-astar;105;114;114;113;113;114;114;113;113;112 13:47:46 INFO - PROCESS | 29548 | |1;audio-beat-detection;155;123;127;153;125;125;124;122;155;123 13:47:46 INFO - PROCESS | 29548 | |2;audio-dft;161;161;162;162;161;162;161;161;161;161 13:47:46 INFO - PROCESS | 29548 | |3;audio-fft;76;76;76;111;76;76;76;75;94;76 13:47:46 INFO - PROCESS | 29548 | |4;audio-oscillator;84;84;117;84;85;84;112;85;85;104 13:47:46 INFO - PROCESS | 29548 | |5;imaging-gaussian-blur;118;118;117;117;117;117;117;117;118;117 13:47:46 INFO - PROCESS | 29548 | |6;imaging-darkroom;149;149;149;149;150;149;149;149;149;149 13:47:46 INFO - PROCESS | 29548 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;95;95 13:47:46 INFO - PROCESS | 29548 | |8;json-parse-financial;58;58;58;59;58;59;60;67;60;60 13:47:46 INFO - PROCESS | 29548 | |9;json-stringify-tinderbox;68;68;68;69;68;68;68;68;68;70 13:47:46 INFO - PROCESS | 29548 | |10;stanford-crypto-aes;86;88;91;94;85;84;84;86;85;83 13:47:46 INFO - PROCESS | 29548 | |11;stanford-crypto-ccm;135;136;144;133;131;132;145;144;211;138 13:47:46 INFO - PROCESS | 29548 | |12;stanford-crypto-pbkdf2;178;181;177;182;180;178;179;182;181;181 13:47:46 INFO - PROCESS | 29548 | |13;stanford-crypto-sha256-iterative;69;69;68;68;69;68;68;68;67;67 13:47:46 INFO - PROCESS | 29548 | __end_tp_report 13:47:46 INFO - PROCESS | 29548 | __start_cc_report 13:47:46 INFO - PROCESS | 29548 | _x_x_mozilla_cycle_collect,1884 13:47:46 INFO - PROCESS | 29548 | __end_cc_report 13:47:46 INFO - PROCESS | 29548 | __startTimestamp1483825666322__endTimestamp 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | ------- Summary: start ------- 13:47:46 INFO - PROCESS | 29548 | Number of tests: 14 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:2.72 (2.4%) stddev-sans-first:0.71 13:47:46 INFO - PROCESS | 29548 | Values: 105.0 114.0 114.0 113.0 113.0 114.0 114.0 113.0 113.0 112.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:126.00 stddev:14.66 (11.6%) stddev-sans-first:13.25 13:47:46 INFO - PROCESS | 29548 | Values: 155.0 123.0 127.0 153.0 125.0 125.0 124.0 122.0 155.0 123.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 13:47:46 INFO - PROCESS | 29548 | Values: 161.0 161.0 162.0 162.0 161.0 162.0 161.0 161.0 161.0 161.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:11.92 (15.7%) stddev-sans-first:12.50 13:47:46 INFO - PROCESS | 29548 | Values: 76.0 76.0 76.0 111.0 76.0 76.0 76.0 75.0 94.0 76.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:84.00 stddev:13.21 (15.7%) stddev-sans-first:13.66 13:47:46 INFO - PROCESS | 29548 | Values: 84.0 84.0 117.0 84.0 85.0 84.0 112.0 85.0 85.0 104.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 13:47:46 INFO - PROCESS | 29548 | Values: 118.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 13:47:46 INFO - PROCESS | 29548 | Values: 149.0 149.0 149.0 149.0 150.0 149.0 149.0 149.0 149.0 149.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:47:46 INFO - PROCESS | 29548 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:59.50 stddev:2.71 (4.6%) stddev-sans-first:2.80 13:47:46 INFO - PROCESS | 29548 | Values: 58.0 58.0 58.0 59.0 58.0 59.0 60.0 67.0 60.0 60.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 13:47:46 INFO - PROCESS | 29548 | Values: 68.0 68.0 68.0 69.0 68.0 68.0 68.0 68.0 68.0 70.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:86.00 stddev:3.47 (4.0%) stddev-sans-first:3.67 13:47:46 INFO - PROCESS | 29548 | Values: 86.0 88.0 91.0 94.0 85.0 84.0 84.0 86.0 85.0 83.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:141.00 stddev:23.80 (16.9%) stddev-sans-first:24.97 13:47:46 INFO - PROCESS | 29548 | Values: 135.0 136.0 144.0 133.0 131.0 132.0 145.0 144.0 211.0 138.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:181.00 stddev:1.79 (1.0%) stddev-sans-first:1.76 13:47:46 INFO - PROCESS | 29548 | Values: 178.0 181.0 177.0 182.0 180.0 178.0 179.0 182.0 181.0 181.0 13:47:46 INFO - PROCESS | 29548 | 13:47:46 INFO - PROCESS | 29548 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 13:47:46 INFO - PROCESS | 29548 | Values: 69.0 69.0 68.0 68.0 69.0 68.0 68.0 68.0 67.0 67.0 13:47:46 INFO - PROCESS | 29548 | -------- Summary: end -------- 13:47:46 INFO - PROCESS | 29548 | 13:47:47 INFO - TEST-INFO | 29548: exit 0 13:47:47 INFO - TEST-OK | kraken | took 63489ms 13:47:47 INFO - SUITE-END | took 1184s 13:47:47 INFO - Completed test suite (00:19:44) 13:47:47 INFO - javascript benchmark 13:47:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2492.152668092848, 2540.0, 2543.6184572314137, 2574.7425257474247, 2592.8055395845317, 4693.249080147177, 4702.177119004177, 4715.6463265255115, 4734.218853889112, 4739.668223224376, 4716.90566188676, 4716.9292460613115, 4776.0, 4785.497522760113, 4828.855134345975, 4351.086271882893, 4378.40891479651, 4416.646668266531, 4436.489803672571, 4458.576435238663, 4031.6169963853386, 4479.8432054878085, 4483.3723278741, 4521.050579378334, 4561.087782443508, 4341.630961368288, 4451.755153466558, 4466.665000124987, 4473.82104715811, 4557.567031132037, 4353.477582690098, 4414.6688998324935, 4422.823087076513, 4425.7565833879125, 4429.402030725875, 4395.780210989437, 4397.032652816375, 4398.978005109954, 4401.845935392246, 4403.251447253974, 4707.270373092176, 4753.762311884392, 4768.0463907218345, 4793.209120495114, 4851.611871050308, 5149.433562308143, 5154.896902061975, 5158.509941555546, 5160.993606246783, 5211.062008838408, 4956.578690811285, 5190.636655434121, 5197.090509160919, 5199.2461093141665, 5204.9219261711105, 4657.976710116428, 4759.833405830779, 4796.928046079312, 4804.111239420718, 4861.854144375674, 5202.2196670499725, 5258.2638430619745, 5261.3686357637325, 5262.000219958196, 5262.184361423985, 4998.4251811042195, 5043.672161309503, 5044.243363495505, 5048.798048078109, 5083.440821509631, 4988.7505624718615, 5018.372703412074, 5021.07110184617, 5030.547250747451, 5091.66904151229, 5010.874728131753, 5062.898742025139, 5064.417591976896, 5084.3644544431945, 5103.617228707862, 5038.5969122470115, 5039.798408063636, 5051.848444546669, 5061.392632884003, 5105.5149760772665, 4333.696641235147, 4371.519132895379, 4408.074304396113, 4424.269995450779, 4459.063596644741, 3735.084904198489, 3736.645018723217, 3740.8503659853845, 3741.644543768338, 3780.3384407728536, 4385.846495372711, 4387.276099443556, 4387.473503179575, 4418.381426600278, 4424.181526417558, 5028.069807085628, 5030.673006254582, 5037.11850426174, 5038.546530812245, 5069.797208111634, 4379.912401751947, 4384.824607015684, 4397.73613583186, 4444.31113177458, 4479.059397526491, 4580.0, 4584.404027476474, 4586.197415452282, 4607.884802879955, 4636.559526844944, 4753.144434001774, 4756.571908528249, 4757.072370887644, 4766.785494652819, 4816.34979277807, 5015.799368025238, 5015.924761128659, 5062.038212739568, 5108.080545501845, 5138.922916156186, 4837.879053023562, 4845.878853028703, 4850.442199147144, 4873.074115917964, 4891.364122664031, 4877.487863774394, 4905.1416002200185, 4905.5094490550655, 5107.9489205107475, 5139.922901156411, 5051.772670229775, 5109.233614957786, 5112.3865136183895, 5130.358705161855, 5139.997700448376, 4815.518448155157, 4853.6602437829015, 4913.361263035782, 4950.6782059166035, 4998.625103117353, 4975.776090076028, 4977.726225057657, 4979.253112033224, 5010.448850626501, 5028.723420211923, 4914.557689807934, 4916.950830491793, 4917.975410122926, 4921.237208232729, 4929.704217746947, 4909.410870695539, 4977.850664480072, 5000.224965130411, 5018.573421259088, 5069.239614057921, 4923.630727695366, 4924.778384972613, 4948.975255123702, 4965.4041515018425, 4969.0], "value": 4681.092468621486, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.8534652970433, 546.7840203119765, 555.0203890133912, 561.5227057001549, 565.6097292867921, 30352.84823575882, 31107.0, 35050.474242886376, 36469.45295820565, 36876.63123368766, 43705.34441983365, 43919.3412098818, 43975.78012109935, 44098.559014409846, 44110.0, 7767.456278060524, 7946.404019698517, 7947.403944704141, 7952.403569732293, 7954.761357159295, 7659.387249020065, 7786.182450842636, 7848.372130229596, 9951.104400603945, 10298.588056477733, 10253.077223049924, 10310.278280520366, 10312.278140530167, 10312.587496500131, 10356.0, 10042.397456152654, 10079.294449388546, 10082.0, 10102.494875256209, 10102.848457273147, 10052.597896084148, 10125.848112278321, 10130.594776208944, 10131.0, 10133.493325333704, 39750.60249397498, 39998.0, 40050.19899601991, 40089.19821603552, 40103.0, 3719.8326075326677, 3767.1523907120954, 3808.27642747877, 3833.233353329317, 3850.7497012694084, 28695.0, 28831.233753249224, 29045.70954290451, 29709.257268568243, 29903.401931961456, 3499.4051011328133, 3528.5060091587197, 3542.6811586957297, 3572.178398968226, 3698.9075273118387, 239104.0, 239136.0, 239499.0, 240246.99999999825, 240901.0, 54734.0, 55051.0, 55060.44939550553, 55187.0, 55250.44749552533, 58692.7065364679, 58876.41123588709, 58910.41089589135, 58952.41047589469, 59046.40953590409, 12516.311602861748, 12661.74676506483, 12739.0, 12740.554080607135, 12742.80885786714, 8356.665733370719, 8739.519326437106, 8777.034526202113, 8791.384603077724, 8800.16398442147, 12974.675633109058, 17343.826561734473, 17645.294188232583, 17817.46547603574, 18454.723179152323, 6732.0, 6786.76246331376, 6855.965720171467, 7059.752908648173, 7102.183248926336, 834.9081601023883, 839.093778718982, 847.9957600211959, 870.3472395703222, 878.3587980774071, 27551.0, 28856.134315970554, 28967.42065158685, 29267.853660731987, 29461.0, 968.0, 988.6935050134481, 992.4690290694443, 993.1310103659298, 1132.923722463663, 15060.472883449245, 15473.381064757283, 15530.223488825739, 15736.449224277096, 15846.524604261891, 321623.0, 323325.0, 323696.0, 324987.0, 325612.0, 52908.206376904374, 53064.73467632714, 53088.469115308355, 53232.467675323525, 53241.73379133079, 1249.9187552809037, 1276.8403949506312, 1282.499825068224, 1289.4906511927736, 1289.8065290206544, 26055.869720651277, 27246.0, 27609.0, 28028.439431210852, 28258.858705707164, 43295.56704432915, 43712.562874372124, 43751.56248437475, 43824.780876095414, 43885.78057109694, 269938.0, 271132.0, 271320.0, 272850.0, 274210.0, 33485.33029339351, 36478.27043459063, 36829.26341473209, 37327.0, 37458.438123428714, 38130.809345953094, 42210.78894605507, 47962.76018620024, 48025.75987120042, 48110.5188948106, 36297.27405451823, 36612.633873660925, 36767.26465470622, 37215.25569488541, 37354.0, 37468.1367893881, 37822.243555129295, 38091.0, 38393.0, 38546.229075418894, 36255.81872090622, 36875.446868297535, 36955.81522092372, 37348.6265137356, 37391.0, 31769.205769855012, 34078.65921340854, 34217.31565368629, 34319.656803431644, 34383.828080859435, 25302.620460693473, 25343.493130137664, 25459.0, 25470.49059018772, 26015.869920650275], "value": 18107.867243817767, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.82839322928373, 367.5644361431704, 369.25963443296195, 380.11243745853466, 387.1289598403592, 16594.33622655094, 17568.47294581163, 18067.81932180678, 18109.818901810977, 18133.4559963201, 15780.684386312267, 17653.55866103348, 17968.910155449204, 18132.637347253047, 18258.452246432596, 5119.4624564420665, 5268.1834315680935, 5293.073712100387, 5299.205119232127, 5299.33758280215, 5158.948410515903, 5196.714309836208, 5230.476952304777, 5248.291480650101, 5260.526552610283, 5037.49625037497, 5148.3564554430695, 5161.741912904358, 5163.7418129093585, 5168.844934651947, 5122.897542049157, 5144.048351055067, 5211.869703257411, 5406.567474602022, 5418.647787893814, 5425.457454254543, 5433.429489903582, 5435.157550579666, 5438.0, 5448.91826622601, 12386.690332741664, 12511.436985335682, 12537.623871283877, 12558.874411255862, 16640.835141540098, 2132.1471411435396, 2159.7948194921455, 2220.367195349334, 2271.693321401606, 2304.320225533472, 9019.368644194908, 9214.032526584679, 11323.263987840764, 12874.742505149938, 13352.399142038632, 1786.4551311850012, 1835.3667984545443, 1948.6979518174705, 1965.49879780655, 1970.103602860695, 64418.677906610166, 74092.25907740908, 78609.60695196487, 79698.20301797024, 80737.59631201865, 9387.249020078378, 9779.234133837148, 9848.162906152915, 10060.798784024279, 10146.746331341776, 13893.513727019506, 13907.58277251684, 13909.513167039106, 14084.577462676136, 14177.362018709182, 6626.635535045619, 6790.864182716318, 6849.349311815369, 6849.760258390932, 6897.206821215625, 6253.6873156342, 6306.905396419057, 6429.646369449725, 6451.48388128958, 6469.482441404676, 4219.409282700424, 4260.382244574519, 4265.1469706058915, 4271.423357846713, 4320.373545835836, 1774.1750086209975, 1812.8005919348861, 1832.6334733053443, 1839.6872531669649, 1846.750688657014, 968.4673429613825, 981.0532835813474, 981.6367943860674, 988.6342053440274, 1004.6082028009082, 16525.33898644065, 16633.3346666132, 16821.579460513585, 17745.11274436299, 17832.465026049238, 954.9474778887089, 962.3263715399248, 970.825251454745, 972.1493693018608, 976.8046390721884, 9048.28082664305, 9414.670486533076, 9650.0, 9697.369670971364, 9839.458829764431, 328792.0, 330133.0, 330166.0, 449357.0, 451088.0, 90346.54826725824, 91859.54070229606, 93353.53323233477, 94131.0, 94997.52501237587, 983.1201075037944, 1003.4430890855499, 1016.2682868334787, 1038.776663017455, 1048.1248157788332, 15303.923480382527, 15366.46217382431, 15759.369625214864, 16219.837801622305, 16420.425285114965, 39456.2108757829, 40418.1916361677, 41127.58872411238, 41156.79421602873, 41369.1726165469, 51864.0729130732, 56915.715421422625, 57268.42731572631, 57570.712146439, 57792.71103644455, 7411.666475008746, 7851.646675899712, 7890.329322007694, 7919.960400197962, 8205.630746616536, 10436.791264174826, 12543.498260069495, 12861.164024338388, 12933.741325173256, 13431.664208394868, 9797.265205109503, 9916.0, 10077.899221007696, 10516.894831051592, 10744.19418543628, 6034.909476357946, 10959.232853700165, 10960.28758130719, 10965.396903170402, 10982.780344392908, 10395.86987523183, 10467.267291289536, 10510.842337365099, 10630.574777008833, 11075.113990880871, 7451.143118541437, 7774.766756997299, 7826.452148349788, 7995.680172793023, 8152.755417337489, 7495.737649182252, 9045.638174472948, 9857.802843943224, 10216.795664086825, 10331.896681033093], "value": 8364.390972363388, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1198.9220700654462, 1319.571139379702, 1324.54965311794, 1324.668832791802, 1334.55959533354, 6240.469560087392, 6844.178698556174, 6881.655917204145, 6913.239543650207, 7161.570305781644, 7034.718611255556, 7089.22018577956, 7102.076730025089, 7117.252688467714, 7119.537230080041, 1801.4055361730664, 2068.420842164193, 2094.1623350659706, 2096.982963262816, 2101.957960840782, 1902.372217168338, 2065.4629796253007, 2111.155537784883, 2112.292382052007, 2131.2007997001124, 2074.8236399906027, 2084.3746875937236, 2087.1651339464183, 2087.916483340672, 2090.560982193741, 1976.49599352165, 1992.7907569705117, 1994.8404127669824, 1995.2517805822818, 1998.6002799440025, 2011.4569066352003, 2020.9090590923445, 2025.9493512662154, 2035.4402539301661, 2049.8565100442975, 7022.613756243404, 7035.577865328097, 7072.575645461237, 7073.221945585981, 7080.221175670724, 4244.236037513247, 4307.073979094478, 4309.07354918694, 4322.0, 4324.783760811947, 2699.6895357033995, 2839.872205750746, 2862.6421697287838, 2877.4101309231555, 2877.812942158753, 1055.3245922609535, 1084.5770149641646, 1128.8814674459145, 1145.8109411947019, 1165.6386520178771, 9044.09559044097, 9095.681651142178, 9098.090190980914, 9115.863262051074, 9300.39547429415, 3788.5453745550712, 4029.7985100744845, 4048.7975601219823, 4053.574374690674, 4064.8170832312617, 7801.29788319054, 7861.135275119732, 7861.724839630586, 7864.056313242448, 8011.399145064027, 4262.637675797592, 4322.135572885435, 4323.567643235651, 4328.805203765838, 4514.119746649372, 3631.8728844490315, 3689.2805902848686, 3990.0, 3991.1419044905483, 4031.596840315945, 3340.981000794781, 3410.4372778491766, 3481.9303613927577, 3517.1558825881602, 3519.7712148710766, 1075.4891426572315, 1076.6554702495282, 1083.539495714334, 1100.884407137255, 1107.7009207513927, 781.6873250699651, 804.6499772598938, 805.9798505037304, 815.4699445360444, 818.1613845807975, 4427.690061695715, 4501.729896206238, 4575.130725162208, 4612.492625811158, 4742.264948932921, 477.92353223484076, 482.70796168318367, 485.9635527335464, 486.1880659298979, 490.4213028626255, 2991.282092297833, 3129.8748050077743, 3130.89041883533, 3154.8895788647287, 3272.787268827519, 7786.4938778979185, 7789.922100778919, 7891.6054197289905, 7921.485103468372, 7928.960355198303, 7858.764237072897, 7874.755694627118, 7877.448578599443, 7877.684892604231, 8040.678372865137, 6220.471259942956, 6404.391582799627, 6416.101745755598, 6421.0, 6475.158229430144, 4135.2763266427655, 4260.573942605714, 4281.593248641374, 4307.784610769387, 4311.202427550787, 7148.642567871481, 7197.316254955771, 7246.601436920809, 7285.708571657075, 7307.159676637255, 7146.964265178641, 7246.6376681166785, 7273.599952002479, 7359.742409015659, 7383.150937642025, 5522.088855338824, 5691.800786972436, 5867.20792692998, 5926.940730592639, 5928.199693041557, 6531.967340163269, 6570.310117437601, 6584.703688333941, 6833.965830170818, 6835.897461537981, 6168.629882207082, 6198.380161983766, 6221.906671400023, 6370.267419246846, 6401.0, 5983.581149319506, 6072.544559157993, 6082.2701275847185, 6088.8782224356155, 6114.694265286629, 5577.107662773937, 5636.1545768133465, 5636.661800291996, 5645.520130788765, 5652.886942261214, 3953.2291203215095, 3982.681385489096, 3983.24318379507, 4084.4894388201474, 4129.566395528426, 4012.9398059029722, 4063.6139566741113, 4098.8360465581045, 4132.33882578798, 4144.689148313828], "value": 3692.1281740712, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.9071450708309, 345.73896707985466, 352.3545044641523, 354.7304048922818, 358.6700235783079, 4869.0, 4927.901441971158, 4988.850334489962, 5035.924461133086, 5055.205402082259, 4637.791299391527, 4688.53114688531, 4827.758612069391, 4830.396200474941, 4950.381202349707, 3917.5690674025836, 3930.683221120928, 3938.901527461822, 3948.940765888514, 3988.641022307999, 3930.1746633206917, 3941.783201923893, 3943.428202910576, 3944.3294639911283, 3970.8610198643055, 3737.7009839212933, 3830.751001184914, 3862.3627101528214, 3893.7274390792654, 3930.1943101664233, 3924.548676902164, 3933.6263055009726, 3933.8033098344968, 3934.980325098385, 3944.9211015779665, 3523.6300188480354, 3531.1172206948263, 3531.4879342495196, 3546.397112490857, 3548.3967725486727, 4569.383133276999, 4816.903661926742, 4826.469088400273, 4828.5412885775795, 4834.299026641117, 4283.678724095706, 4290.2492063889, 4299.204647140258, 4301.634361079312, 4324.978375108104, 5129.256257842592, 5129.820456284049, 5133.409657889353, 5140.845774626767, 5148.3821941366905, 3597.982010089933, 3599.3341231872187, 3615.132368231632, 3616.6383361663884, 3619.2942376236642, 5190.558802501791, 5332.466753324676, 5347.315595587455, 5378.924215156984, 5386.973065134649, 3638.472421498868, 3657.652523010363, 3663.6519530644546, 3669.8165091745304, 3685.299793039314, 3703.8703645372284, 3708.517892673935, 3709.573399059143, 3709.684676802448, 3716.7769933803515, 4150.460440142822, 4293.44185255921, 4315.935260971088, 4315.978420107942, 4317.978410107992, 5154.139452603766, 5174.14626586609, 5175.17197248446, 5181.5077567631015, 5201.791928322825, 1253.4484826676417, 1256.9937150314372, 1257.798752199662, 1268.7018550640591, 1269.5302737987004, 804.7424824056246, 829.5271695133837, 839.3327304792582, 855.6491838346249, 857.9656813727381, 4388.253996820548, 4394.956050439519, 4396.978015109968, 4399.186150562156, 4411.492678341968, 5086.415062267886, 5089.0, 5108.618046922236, 5112.182050871843, 5135.380955712613, 4263.76549289792, 4396.582324679151, 4415.911681766347, 4429.3134564141965, 4550.13547425988, 2689.220126163391, 2698.908346031841, 2743.561030235153, 2755.531559634867, 2755.5395640310267, 4775.307580400822, 4792.472827988919, 4802.327674125625, 4825.094087835286, 4831.680793219204, 4991.401031876198, 5196.428392876781, 5203.739813009334, 5213.48090039222, 5225.451327610546, 4796.976015119972, 4800.66395352329, 4809.927851082237, 4811.27830825372, 4832.903341933212, 5064.5948324134715, 5084.2627818966785, 5127.974360128175, 5130.6152038598, 5147.459516750687, 4921.188003979447, 5059.038782631288, 5081.669691470042, 5174.353205849269, 5216.686998780086, 4951.405831300267, 4958.925616115834, 4960.859002429389, 4963.875903102451, 4966.950330496648, 3662.6520480554304, 3684.723645726635, 3705.2404257126686, 3706.7405281630026, 3708.647678470541, 5104.0812653721205, 5223.895522089613, 5230.424653288212, 5289.523942845162, 5292.708901010481, 5089.236614507706, 5103.285540024473, 5104.744762761921, 5117.0, 5117.104506711236, 5081.314022607048, 5097.406328538445, 5100.846974590768, 5104.2088476286235, 5117.43708192106, 4917.508249175054, 4962.801487940442, 5003.949960500348, 5047.2681461187185, 5052.8989420210655, 4888.706677599355, 4986.750662466789, 5054.16606259963, 5178.145605974972, 5184.507471790174, 5048.798048078036, 5067.974660126676, 5068.29043933857, 5070.670406423571, 5079.612582086414], "value": 3851.5518313401917, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.2489849731074, 647.685872351909, 718.9611012087536, 756.6330329790053, 765.4527013185572, 11553.075753939685, 12328.075394345415, 12337.506499740022, 12342.691432714186, 12351.32067736274, 12142.210756300834, 12172.513099476033, 12183.0, 12188.085893557974, 12311.0, 659.910912026875, 673.6867356679144, 776.471999040655, 783.9608019599011, 796.625585974591, 781.890535325055, 782.9451938364317, 787.1262898183012, 791.0230864881867, 791.9960400197991, 841.524538635672, 843.0894633795517, 843.1063073142458, 843.2537204573965, 843.4601854812925, 689.5586824432368, 704.3801454719813, 839.7984483723924, 839.8866153069318, 840.0968958369747, 598.6677394046303, 612.0299325568978, 613.2824595223599, 615.310851845931, 615.3630991923338, 12929.676758081028, 12947.48210071596, 12985.0, 12990.350482475838, 13004.024698147678, 11655.883441165564, 11688.590899318484, 11857.762844743056, 12099.0, 12278.912584860595, 11389.430528473544, 11567.479463424166, 11585.246958947642, 11595.884041159648, 11772.234804737665, 3629.328574213779, 3654.14127679995, 3670.210904655485, 3719.9070023249637, 3733.962660373416, 58998.922344223975, 59961.99999999956, 60175.0, 60690.0, 62169.37830621681, 10156.39061656303, 10166.68495446348, 10228.335158214692, 10229.079382855578, 10422.0, 17593.29626814913, 17613.0, 17624.471265862045, 17649.647007060044, 17786.37747678825, 6115.205023347025, 6127.724252408652, 6166.352532984062, 6252.812415627538, 6286.465650419674, 3167.382360439738, 3216.951745723816, 3241.7244534214856, 3287.391832510993, 3335.71646410053, 2782.0, 2817.29567608098, 2839.758620517238, 2844.033028770228, 2850.529662605647, 477.2030708716452, 481.2108142646063, 482.6910777102657, 483.95402436769155, 487.3712910345685, 549.8432946610197, 553.3166539323931, 554.1964151979646, 568.7980766827717, 572.9799457018985, 8063.758087257392, 8536.359773016924, 8589.484630922165, 8825.558722063872, 8876.911230887737, 564.3340857787849, 575.2349375330883, 581.4040608376448, 588.808637192914, 606.5693357716071, 3916.2559113768293, 4001.4598029265676, 4095.774732389747, 4137.441445404832, 4179.2477354076555, 63368.68315658484, 63737.36262637407, 64056.0, 65545.34454655487, 65677.0, 54781.178282325796, 55527.44472555303, 56436.71781641065, 57329.0, 58250.12624810631, 51347.36064968614, 51735.48264517382, 51799.0, 52676.73661631667, 53800.19299710508, 7838.764837054898, 7999.280064794197, 8084.110747817853, 8098.433109682265, 8148.5518296494265, 36040.27919441649, 36211.63788362188, 36922.26155476929, 37104.628953711195, 37356.06609834667, 40208.396874046324, 40508.0, 42249.366259505514, 44247.336289955034, 45028.32457513074, 11114.888851111386, 11228.214025018171, 11359.327918903173, 11446.82829757571, 12102.334371609646, 12780.36098195068, 12917.677058073623, 13149.802752958522, 13290.667733306746, 13449.596512104652, 12178.756424871275, 12311.0, 12481.812772808596, 12550.0, 13003.934980325037, 12138.332391718188, 12158.75682486363, 12406.503739850667, 12788.488460461478, 12946.352682366032, 11715.414229288672, 11732.94133529327, 11767.588134415255, 12061.276323420623, 12124.969377602649, 9754.219662427131, 10052.296339256181, 10078.647247346307, 10130.949345253226, 10478.476076196312, 10170.288079834341, 10417.895821041995, 10478.37129772216, 10478.63324783629, 10489.423081730578], "value": 6224.8460912138735, "unit": "score"}], "name": "dromaeo_css", "value": 6304.116677114635, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 127.0, 153.0, 125.0, 125.0, 124.0, 122.0, 155.0, 123.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 111.0, 76.0, 76.0, 76.0, 75.0, 94.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 117.0, 84.0, 85.0, 84.0, 112.0, 85.0, 85.0, 104.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 150.0, 149.0, 149.0, 149.0, 149.0, 149.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 59.0, 58.0, 59.0, 60.0, 67.0, 60.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 70.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 91.0, 94.0, 85.0, 84.0, 84.0, 86.0, 85.0, 83.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 144.0, 133.0, 131.0, 132.0, 145.0, 144.0, 211.0, 138.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 181.0, 177.0, 182.0, 180.0, 178.0, 179.0, 182.0, 181.0, 181.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 67.0, 67.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1549.6000000000001, "alertThreshold": 2.0}]} 13:47:48 INFO - Return code: 0 13:47:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:47:48 INFO - # TBPL SUCCESS # 13:47:48 INFO - Running post-action listener: _resource_record_post_action 13:47:48 INFO - [mozharness: 2017-01-07 21:47:48.135379Z] Finished run-tests step (success) 13:47:48 INFO - Running post-run listener: _resource_record_post_run 13:47:48 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 384020480; Read time: 12; Write time: 254608 13:47:48 INFO - TinderboxPrint: CPU usage
13.1% 13:47:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:47:48 INFO - TinderboxPrint: I/O write bytes / time
384,020,480 / 254,608 13:47:48 INFO - TinderboxPrint: CPU idle
8,337.5 (86.9%) 13:47:48 INFO - TinderboxPrint: CPU user
1,213.2 (12.6%) 13:47:48 INFO - TinderboxPrint: Swap in / out
0 / 0 13:47:48 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99389440; Read time: 0; Write time: 73652 13:47:48 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284254208; Read time: 12; Write time: 180932 13:47:48 INFO - Running post-run listener: _upload_blobber_files 13:47:48 INFO - Blob upload gear active. 13:47:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:47:48 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 13:47:48 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:47:48 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:47:49 INFO - (blobuploader) - INFO - Open directory for files ... 13:47:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:47:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:47:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:50 INFO - (blobuploader) - INFO - Done attempting. 13:47:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:47:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:47:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:50 INFO - (blobuploader) - INFO - Done attempting. 13:47:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:47:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:47:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:51 INFO - (blobuploader) - INFO - Done attempting. 13:47:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:47:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:47:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:54 INFO - (blobuploader) - INFO - Done attempting. 13:47:54 INFO - (blobuploader) - INFO - Iteration through files over. 13:47:54 INFO - Return code: 0 13:47:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:47:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:47:54 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/498a8272022505fa00c24a2a58f27aa79aae32102aab0254f0cadae860bf1ff598fc96b71a1eb6409351504e7ee83f0047a69446c02e5f9a0277d8970a556cbd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/278b8f4e8a7f8de25656148f4ed55f8650dc201ac12d4ff009549a2d6f87eaa88aca67f72d622b30a778af9bc267bc6c08b83e96fab1fa65ef229a724cdad29e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b412eda46fad522fddba5472e2f8b4fa0c606dddb3291bede74505b662d2d4d7a19c270ee8569f223bbf67fbdcf763064ca19ba6f94d11073457c62d69fc3d05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ef24e0f37dfc75ec9574fbf5a4d1b4db85d6d2cb814a4c2ba16a092935981584bce05cb520b43070fa9b6c17a1ed9f454df465166a598ac1c3babeefa6625fa"} 13:47:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:47:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:47:54 INFO - Contents: 13:47:54 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/498a8272022505fa00c24a2a58f27aa79aae32102aab0254f0cadae860bf1ff598fc96b71a1eb6409351504e7ee83f0047a69446c02e5f9a0277d8970a556cbd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/278b8f4e8a7f8de25656148f4ed55f8650dc201ac12d4ff009549a2d6f87eaa88aca67f72d622b30a778af9bc267bc6c08b83e96fab1fa65ef229a724cdad29e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b412eda46fad522fddba5472e2f8b4fa0c606dddb3291bede74505b662d2d4d7a19c270ee8569f223bbf67fbdcf763064ca19ba6f94d11073457c62d69fc3d05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ef24e0f37dfc75ec9574fbf5a4d1b4db85d6d2cb814a4c2ba16a092935981584bce05cb520b43070fa9b6c17a1ed9f454df465166a598ac1c3babeefa6625fa"} 13:47:54 INFO - Running post-run listener: copy_logs_to_upload_dir 13:47:54 INFO - Copying logs to upload dir... 13:47:54 INFO - mkdir: /builds/slave/test/build/upload/logs 13:47:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.279634 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-07 13:47:54.720909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 13:47:54.724483) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/498a8272022505fa00c24a2a58f27aa79aae32102aab0254f0cadae860bf1ff598fc96b71a1eb6409351504e7ee83f0047a69446c02e5f9a0277d8970a556cbd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/278b8f4e8a7f8de25656148f4ed55f8650dc201ac12d4ff009549a2d6f87eaa88aca67f72d622b30a778af9bc267bc6c08b83e96fab1fa65ef229a724cdad29e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b412eda46fad522fddba5472e2f8b4fa0c606dddb3291bede74505b662d2d4d7a19c270ee8569f223bbf67fbdcf763064ca19ba6f94d11073457c62d69fc3d05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ef24e0f37dfc75ec9574fbf5a4d1b4db85d6d2cb814a4c2ba16a092935981584bce05cb520b43070fa9b6c17a1ed9f454df465166a598ac1c3babeefa6625fa"} build_url:https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011856 build_url: 'https://queue.taskcluster.net/v1/task/XTphTwlqQl2W1JYiXAOyaw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/498a8272022505fa00c24a2a58f27aa79aae32102aab0254f0cadae860bf1ff598fc96b71a1eb6409351504e7ee83f0047a69446c02e5f9a0277d8970a556cbd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/278b8f4e8a7f8de25656148f4ed55f8650dc201ac12d4ff009549a2d6f87eaa88aca67f72d622b30a778af9bc267bc6c08b83e96fab1fa65ef229a724cdad29e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b412eda46fad522fddba5472e2f8b4fa0c606dddb3291bede74505b662d2d4d7a19c270ee8569f223bbf67fbdcf763064ca19ba6f94d11073457c62d69fc3d05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ef24e0f37dfc75ec9574fbf5a4d1b4db85d6d2cb814a4c2ba16a092935981584bce05cb520b43070fa9b6c17a1ed9f454df465166a598ac1c3babeefa6625fa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 13:47:54.755149) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:47:54.755869) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1483824427.173935-2075387396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 13:47:54.836199) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 13:47:54.836724) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 13:47:54.837291) ========= ========= Total master_lag: 0.17 =========