builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-080 starttime: 1492357570.27 results: success (0) revision: 2ee12fbb228baa2b0548736fa79823246cbf95fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:10.272496) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:10.273035) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:10.273362) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:10.316412) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:10.316895) ========= 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=781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-16 08:46:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.0M=0s 2017-04-16 08:46:10 (37.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:10.475678) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:10.476103) ========= 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=781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.101146 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:10.589823) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-16 08:46:10.590246) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2ee12fbb228baa2b0548736fa79823246cbf95fa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2ee12fbb228baa2b0548736fa79823246cbf95fa --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=781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-16 08:46:10,986 truncating revision to first 12 chars 2017-04-16 08:46:10,986 Setting DEBUG logging. 2017-04-16 08:46:10,986 attempt 1/10 2017-04-16 08:46:10,986 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2ee12fbb228b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-16 08:46:12,073 unpacking tar archive at: mozilla-aurora-2ee12fbb228b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.390607 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-16 08:46:11.995789) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:11.996216) ========= script_repo_revision: 2ee12fbb228baa2b0548736fa79823246cbf95fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:11.996694) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:11.997060) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-16 08:46:12.007590) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-04-16 08:46:12.008093) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:46:12 INFO - MultiFileLogger online at 20170416 08:46:12 in /builds/slave/test 08:46:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 08:46:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:46:12 INFO - {'append_to_log': False, 08:46:12 INFO - 'base_work_dir': '/builds/slave/test', 08:46:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 08:46:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:46:12 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 08:46:12 INFO - 'buildbot_json_path': 'buildprops.json', 08:46:12 INFO - 'config_files': ('talos/linux_config.py',), 08:46:12 INFO - 'default_actions': ('clobber', 08:46:12 INFO - 'read-buildbot-config', 08:46:12 INFO - 'download-and-extract', 08:46:12 INFO - 'populate-webroot', 08:46:12 INFO - 'create-virtualenv', 08:46:12 INFO - 'install', 08:46:12 INFO - 'run-tests'), 08:46:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:46:12 INFO - 'download_minidump_stackwalk': True, 08:46:12 INFO - 'download_symbols': 'ondemand', 08:46:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:46:12 INFO - 'tooltool.py': '/tools/tooltool.py', 08:46:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:46:12 INFO - '/tools/misc-python/virtualenv.py')}, 08:46:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:46:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:46:12 INFO - 'gecko_profile': False, 08:46:12 INFO - 'gecko_profile_interval': 0, 08:46:12 INFO - 'installer_path': 'installer.exe', 08:46:12 INFO - 'log_level': 'info', 08:46:12 INFO - 'log_name': 'talos', 08:46:12 INFO - 'log_to_console': True, 08:46:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:46:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:46:12 INFO - 'opt_config_files': (), 08:46:12 INFO - 'pip_index': False, 08:46:12 INFO - 'suite': 'dromaeojs', 08:46:12 INFO - 'system_bits': '32', 08:46:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:46:12 INFO - 'title': 'talos-linux64-ix-080', 08:46:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:46:12 INFO - 'use_talos_json': True, 08:46:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:46:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:46:12 INFO - 'work_dir': 'build'} 08:46:12 INFO - [mozharness: 2017-04-16 15:46:12.638805Z] Running clobber step. 08:46:12 INFO - Running pre-action listener: _resource_record_pre_action 08:46:12 INFO - Running main action method: clobber 08:46:12 INFO - rmtree: /builds/slave/test/build 08:46:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:46:18 INFO - Running post-action listener: _resource_record_post_action 08:46:18 INFO - [mozharness: 2017-04-16 15:46:18.472255Z] Finished clobber step (success) 08:46:18 INFO - [mozharness: 2017-04-16 15:46:18.472420Z] Running read-buildbot-config step. 08:46:18 INFO - Running pre-action listener: _resource_record_pre_action 08:46:18 INFO - Running main action method: read_buildbot_config 08:46:18 INFO - Using buildbot properties: 08:46:18 INFO - { 08:46:18 INFO - "project": "", 08:46:18 INFO - "product": "firefox", 08:46:18 INFO - "who": "ffxbld@noreply.mozilla.org", 08:46:18 INFO - "installer_path": "public/build/target.tar.bz2", 08:46:18 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:46:18 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 08:46:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 08:46:18 INFO - "stage_platform": "linux64", 08:46:18 INFO - "basedir": "/builds/slave/test", 08:46:18 INFO - "buildnumber": 132, 08:46:18 INFO - "platform": "ubuntu64_hw", 08:46:18 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:46:18 INFO - "slavebuilddir": "test", 08:46:18 INFO - "taskId": "DqPHJj1HSrCYIxXjo3PRxw", 08:46:18 INFO - "branch": "mozilla-aurora", 08:46:18 INFO - "script_repo_revision": "production", 08:46:18 INFO - "revision": "2ee12fbb228baa2b0548736fa79823246cbf95fa", 08:46:18 INFO - "slavename": "talos-linux64-ix-080", 08:46:18 INFO - "repo_path": "releases/mozilla-aurora" 08:46:18 INFO - } 08:46:18 INFO - Finding installer, test and symbols from parent task. 08:46:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DqPHJj1HSrCYIxXjo3PRxw'}, attempt #1 08:46:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DqPHJj1HSrCYIxXjo3PRxw'}, attempt #1 08:46:19 INFO - Task dependencies: Fp5mO7BgSNeLwcqYWpziWQ 08:46:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ'}, attempt #1 08:46:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.tar.bz2 08:46:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/test_packages.json 08:46:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.crashreporter-symbols.zip 08:46:19 INFO - Running post-action listener: _resource_record_post_action 08:46:19 INFO - [mozharness: 2017-04-16 15:46:19.877804Z] Finished read-buildbot-config step (success) 08:46:19 INFO - [mozharness: 2017-04-16 15:46:19.878019Z] Running download-and-extract step. 08:46:19 INFO - Running pre-action listener: _resource_record_pre_action 08:46:19 INFO - Running main action method: download_and_extract 08:46:19 INFO - mkdir: /builds/slave/test/build/tests 08:46:19 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')]} 08:46:19 INFO - trying https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.test_packages.json 08:46:19 INFO - Downloading https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:46:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:46:20 INFO - Downloaded 896 bytes. 08:46:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:46:20 INFO - Using the following test package requirements: 08:46:20 INFO - {u'common': [u'target.common.tests.zip'], 08:46:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:46:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:46:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:46:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:46:20 INFO - u'mozbase': [u'target.common.tests.zip'], 08:46:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:46:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:46:20 INFO - u'web-platform': [u'target.common.tests.zip', 08:46:20 INFO - u'target.web-platform.tests.zip'], 08:46:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:46:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:46:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.common.tests.zip 08:46:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:46:20 INFO - Fetch https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.common.tests.zip into memory 08:46:21 INFO - Expected file size: 59543110 08:46:21 INFO - Obtained file size: 59543110 08:46:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:46:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.common.tests.zip 08:46:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:46:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.common.tests.zip into memory 08:46:32 INFO - Expected file size: 59543110 08:46:32 INFO - Obtained file size: 59543110 08:46:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.talos.tests.zip 08:46:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:46:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.talos.tests.zip into memory 08:46:43 INFO - Expected file size: 14404424 08:46:43 INFO - Obtained file size: 14404424 08:46:44 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')]} 08:46:44 INFO - trying https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.tar.bz2 08:46:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:46:45 INFO - Downloaded 66767485 bytes. 08:46:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.tar.bz2 08:46:45 INFO - mkdir: /builds/slave/test/properties 08:46:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:45 INFO - Contents: 08:46:45 INFO - build_url:https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.tar.bz2 08:46:45 INFO - Running post-action listener: _resource_record_post_action 08:46:45 INFO - Running post-action listener: set_extra_try_arguments 08:46:45 INFO - [mozharness: 2017-04-16 15:46:45.211711Z] Finished download-and-extract step (success) 08:46:45 INFO - [mozharness: 2017-04-16 15:46:45.211807Z] Running populate-webroot step. 08:46:45 INFO - Running pre-action listener: _resource_record_pre_action 08:46:45 INFO - Running main action method: populate_webroot 08:46:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:45 INFO - '--tppagecycles', 08:46:45 INFO - '7'], 08:46:45 INFO - 'tests': ['tsvgm']}}, 08:46:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:46:45 INFO - 'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:46:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:46:45 INFO - 'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['damp', 'tps']}, 08:46:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:46:45 INFO - 'tests': ['damp', 'tps']}, 08:46:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['dromaeo_dom']}, 08:46:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['basic_compositor_video']}, 08:46:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:46:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['a11yr', 08:46:45 INFO - 'ts_paint', 08:46:45 INFO - 'tpaint', 08:46:45 INFO - 'sessionrestore', 08:46:45 INFO - 'sessionrestore_no_auto_restore', 08:46:45 INFO - 'tabpaint']}, 08:46:45 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:45 INFO - 'ts_paint', 08:46:45 INFO - 'tpaint', 08:46:45 INFO - 'sessionrestore', 08:46:45 INFO - 'sessionrestore_no_auto_restore', 08:46:45 INFO - 'tabpaint']}, 08:46:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:45 INFO - 'ts_paint', 08:46:45 INFO - 'tpaint', 08:46:45 INFO - 'sessionrestore', 08:46:45 INFO - 'sessionrestore_no_auto_restore', 08:46:45 INFO - 'tabpaint']}, 08:46:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:45 INFO - 'ts_paint', 08:46:45 INFO - 'tpaint', 08:46:45 INFO - 'sessionrestore', 08:46:45 INFO - 'sessionrestore_no_auto_restore', 08:46:45 INFO - 'tabpaint']}, 08:46:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['a11yr', 08:46:45 INFO - 'ts_paint', 08:46:45 INFO - 'tpaint', 08:46:45 INFO - 'sessionrestore', 08:46:45 INFO - 'sessionrestore_no_auto_restore', 08:46:45 INFO - 'tabpaint']}, 08:46:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['a11yr', 08:46:45 INFO - 'ts_paint', 08:46:45 INFO - 'tpaint', 08:46:45 INFO - 'sessionrestore', 08:46:45 INFO - 'sessionrestore_no_auto_restore', 08:46:45 INFO - 'tabpaint']}, 08:46:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['tsvgx', 08:46:45 INFO - 'tsvgr_opacity', 08:46:45 INFO - 'tart', 08:46:45 INFO - 'tscrollx', 08:46:45 INFO - 'cart', 08:46:45 INFO - 'tsvg_static']}, 08:46:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:45 INFO - 'tsvgr_opacity', 08:46:45 INFO - 'tart', 08:46:45 INFO - 'tscrollx', 08:46:45 INFO - 'cart', 08:46:45 INFO - 'tsvg_static']}, 08:46:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:46:45 INFO - 'talos_options': ['--disable-e10s'], 08:46:45 INFO - 'tests': ['tp5o']}, 08:46:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:46:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:46:45 INFO - 'talos_options': ['--disable-e10s', 08:46:45 INFO - '--xperf_path', 08:46:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:45 INFO - 'tests': ['tp5n']}, 08:46:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:46:45 INFO - 'talos_options': ['--xperf_path', 08:46:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:45 INFO - 'tests': ['tp5n']}}, 08:46:45 INFO - 'talos.zip': {'path': '', 08:46:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:45 INFO - Running post-action listener: _resource_record_post_action 08:46:45 INFO - [mozharness: 2017-04-16 15:46:45.224867Z] Finished populate-webroot step (success) 08:46:45 INFO - [mozharness: 2017-04-16 15:46:45.224957Z] Running create-virtualenv step. 08:46:45 INFO - Running pre-action listener: _resource_record_pre_action 08:46:45 INFO - Running main action method: create_virtualenv 08:46:45 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:45 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 08:46:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:45 INFO - Using real prefix '/usr' 08:46:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:45 INFO - Installing distribute.............................................................................................................................................................................................done. 08:46:47 INFO - Installing pip.................done. 08:46:47 INFO - Return code: 0 08:46:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:46:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:46:48 INFO - Reading from file tmpfile_stdout 08:46:48 INFO - Output received: 08:46:48 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) 08:46:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:46:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:46:48 INFO - Using env: {'DISPLAY': ':0', 08:46:48 INFO - 'HOME': '/home/cltbld', 08:46:48 INFO - 'LANG': 'en_US.UTF-8', 08:46:48 INFO - 'LANGUAGE': 'en_US:en', 08:46:48 INFO - 'LOGNAME': 'cltbld', 08:46:48 INFO - 'MAIL': '/var/mail/cltbld', 08:46:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:48 INFO - 'MOZ_NO_REMOTE': '1', 08:46:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:48 INFO - 'NO_EM_RESTART': '1', 08:46:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:48 INFO - 'PWD': '/builds/slave/test', 08:46:48 INFO - 'SHELL': '/bin/bash', 08:46:48 INFO - 'SHLVL': '1', 08:46:48 INFO - 'TERM': 'linux', 08:46:48 INFO - 'TMOUT': '86400', 08:46:48 INFO - 'USER': 'cltbld', 08:46:48 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:46:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:48 INFO - '_': '/tools/buildbot/bin/python'} 08:46:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:48 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 08:46:48 INFO - Cleaning up... 08:46:48 INFO - Return code: 0 08:46:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:46:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:46:48 INFO - Using env: {'DISPLAY': ':0', 08:46:48 INFO - 'HOME': '/home/cltbld', 08:46:48 INFO - 'LANG': 'en_US.UTF-8', 08:46:48 INFO - 'LANGUAGE': 'en_US:en', 08:46:48 INFO - 'LOGNAME': 'cltbld', 08:46:48 INFO - 'MAIL': '/var/mail/cltbld', 08:46:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:48 INFO - 'MOZ_NO_REMOTE': '1', 08:46:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:48 INFO - 'NO_EM_RESTART': '1', 08:46:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:48 INFO - 'PWD': '/builds/slave/test', 08:46:48 INFO - 'SHELL': '/bin/bash', 08:46:48 INFO - 'SHLVL': '1', 08:46:48 INFO - 'TERM': 'linux', 08:46:48 INFO - 'TMOUT': '86400', 08:46:48 INFO - 'USER': 'cltbld', 08:46:48 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:46:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:48 INFO - '_': '/tools/buildbot/bin/python'} 08:46:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:48 INFO - Downloading/unpacking psutil>=3.1.1 08:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:46:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:50 INFO - Installing collected packages: psutil 08:46:50 INFO - Running setup.py install for psutil 08:46:50 INFO - building 'psutil._psutil_linux' extension 08:46:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:46:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:46:51 INFO - building 'psutil._psutil_posix' extension 08:46:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:46:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:46:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:52 INFO - Successfully installed psutil 08:46:52 INFO - Cleaning up... 08:46:52 INFO - Return code: 0 08:46:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:46:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:46:52 INFO - Using env: {'DISPLAY': ':0', 08:46:52 INFO - 'HOME': '/home/cltbld', 08:46:52 INFO - 'LANG': 'en_US.UTF-8', 08:46:52 INFO - 'LANGUAGE': 'en_US:en', 08:46:52 INFO - 'LOGNAME': 'cltbld', 08:46:52 INFO - 'MAIL': '/var/mail/cltbld', 08:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:52 INFO - 'MOZ_NO_REMOTE': '1', 08:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:52 INFO - 'NO_EM_RESTART': '1', 08:46:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:52 INFO - 'PWD': '/builds/slave/test', 08:46:52 INFO - 'SHELL': '/bin/bash', 08:46:52 INFO - 'SHLVL': '1', 08:46:52 INFO - 'TERM': 'linux', 08:46:52 INFO - 'TMOUT': '86400', 08:46:52 INFO - 'USER': 'cltbld', 08:46:52 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:52 INFO - '_': '/tools/buildbot/bin/python'} 08:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:46:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:46:54 INFO - Installing collected packages: mozsystemmonitor 08:46:54 INFO - Running setup.py install for mozsystemmonitor 08:46:54 INFO - Successfully installed mozsystemmonitor 08:46:54 INFO - Cleaning up... 08:46:54 INFO - Return code: 0 08:46:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:46:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:46:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:46:54 INFO - Using env: {'DISPLAY': ':0', 08:46:54 INFO - 'HOME': '/home/cltbld', 08:46:54 INFO - 'LANG': 'en_US.UTF-8', 08:46:54 INFO - 'LANGUAGE': 'en_US:en', 08:46:54 INFO - 'LOGNAME': 'cltbld', 08:46:54 INFO - 'MAIL': '/var/mail/cltbld', 08:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:54 INFO - 'MOZ_NO_REMOTE': '1', 08:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:54 INFO - 'NO_EM_RESTART': '1', 08:46:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:54 INFO - 'PWD': '/builds/slave/test', 08:46:54 INFO - 'SHELL': '/bin/bash', 08:46:54 INFO - 'SHLVL': '1', 08:46:54 INFO - 'TERM': 'linux', 08:46:54 INFO - 'TMOUT': '86400', 08:46:54 INFO - 'USER': 'cltbld', 08:46:54 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:54 INFO - '_': '/tools/buildbot/bin/python'} 08:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:54 INFO - Downloading/unpacking jsonschema==2.5.1 08:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:46:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:57 INFO - Downloading functools32-3.2.3-2.tar.gz 08:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:46:57 INFO - warning: no files found matching '*.txt' 08:46:57 INFO - no previously-included directories found matching 'build' 08:46:57 INFO - no previously-included directories found matching 'dist' 08:46:57 INFO - no previously-included directories found matching '.git*' 08:46:57 INFO - Installing collected packages: jsonschema, functools32 08:46:57 INFO - Running setup.py install for functools32 08:46:57 INFO - warning: no files found matching '*.txt' 08:46:57 INFO - no previously-included directories found matching 'build' 08:46:57 INFO - no previously-included directories found matching 'dist' 08:46:57 INFO - no previously-included directories found matching '.git*' 08:46:57 INFO - Successfully installed jsonschema functools32 08:46:57 INFO - Cleaning up... 08:46:57 INFO - Return code: 0 08:46:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:46:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:46:57 INFO - Using env: {'DISPLAY': ':0', 08:46:57 INFO - 'HOME': '/home/cltbld', 08:46:57 INFO - 'LANG': 'en_US.UTF-8', 08:46:57 INFO - 'LANGUAGE': 'en_US:en', 08:46:57 INFO - 'LOGNAME': 'cltbld', 08:46:57 INFO - 'MAIL': '/var/mail/cltbld', 08:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:57 INFO - 'MOZ_NO_REMOTE': '1', 08:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:57 INFO - 'NO_EM_RESTART': '1', 08:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:57 INFO - 'PWD': '/builds/slave/test', 08:46:57 INFO - 'SHELL': '/bin/bash', 08:46:57 INFO - 'SHLVL': '1', 08:46:57 INFO - 'TERM': 'linux', 08:46:57 INFO - 'TMOUT': '86400', 08:46:57 INFO - 'USER': 'cltbld', 08:46:57 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:57 INFO - '_': '/tools/buildbot/bin/python'} 08:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:46:57 INFO - Cleaning up... 08:46:57 INFO - Return code: 0 08:46:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:46:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:46:57 INFO - Using env: {'DISPLAY': ':0', 08:46:57 INFO - 'HOME': '/home/cltbld', 08:46:57 INFO - 'LANG': 'en_US.UTF-8', 08:46:57 INFO - 'LANGUAGE': 'en_US:en', 08:46:57 INFO - 'LOGNAME': 'cltbld', 08:46:57 INFO - 'MAIL': '/var/mail/cltbld', 08:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:57 INFO - 'MOZ_NO_REMOTE': '1', 08:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:57 INFO - 'NO_EM_RESTART': '1', 08:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:57 INFO - 'PWD': '/builds/slave/test', 08:46:57 INFO - 'SHELL': '/bin/bash', 08:46:57 INFO - 'SHLVL': '1', 08:46:57 INFO - 'TERM': 'linux', 08:46:57 INFO - 'TMOUT': '86400', 08:46:57 INFO - 'USER': 'cltbld', 08:46:57 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:57 INFO - '_': '/tools/buildbot/bin/python'} 08:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:57 INFO - Downloading/unpacking blobuploader==1.2.4 08:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:59 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:47:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:00 INFO - Downloading docopt-0.6.1.tar.gz 08:47:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:47:00 INFO - Installing collected packages: blobuploader, requests, docopt 08:47:00 INFO - Running setup.py install for blobuploader 08:47:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:47:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:47:00 INFO - Running setup.py install for requests 08:47:01 INFO - Running setup.py install for docopt 08:47:01 INFO - Successfully installed blobuploader requests docopt 08:47:01 INFO - Cleaning up... 08:47:01 INFO - Return code: 0 08:47:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:47:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:47:01 INFO - Using env: {'DISPLAY': ':0', 08:47:01 INFO - 'HOME': '/home/cltbld', 08:47:01 INFO - 'LANG': 'en_US.UTF-8', 08:47:01 INFO - 'LANGUAGE': 'en_US:en', 08:47:01 INFO - 'LOGNAME': 'cltbld', 08:47:01 INFO - 'MAIL': '/var/mail/cltbld', 08:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:01 INFO - 'MOZ_NO_REMOTE': '1', 08:47:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:01 INFO - 'NO_EM_RESTART': '1', 08:47:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:01 INFO - 'PWD': '/builds/slave/test', 08:47:01 INFO - 'SHELL': '/bin/bash', 08:47:01 INFO - 'SHLVL': '1', 08:47:01 INFO - 'TERM': 'linux', 08:47:01 INFO - 'TMOUT': '86400', 08:47:01 INFO - 'USER': 'cltbld', 08:47:01 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:01 INFO - '_': '/tools/buildbot/bin/python'} 08:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:47:01 INFO - Running setup.py (path:/tmp/pip-5SakFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:47:01 INFO - Running setup.py (path:/tmp/pip-S0ctMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:47:01 INFO - Running setup.py (path:/tmp/pip-PWIoXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:47:01 INFO - Running setup.py (path:/tmp/pip-AO8AyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:47:01 INFO - Running setup.py (path:/tmp/pip-5RI8gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:47:01 INFO - Running setup.py (path:/tmp/pip-m0g_qR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:47:01 INFO - Running setup.py (path:/tmp/pip-6mWK7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:47:02 INFO - Running setup.py (path:/tmp/pip-dQY9si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:47:02 INFO - Running setup.py (path:/tmp/pip-2vlwWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:47:02 INFO - Running setup.py (path:/tmp/pip-jq3vZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:47:02 INFO - Running setup.py (path:/tmp/pip-B6j40G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:47:02 INFO - Running setup.py (path:/tmp/pip-UZxKZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:47:02 INFO - Running setup.py (path:/tmp/pip-oYRYkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:47:02 INFO - Running setup.py (path:/tmp/pip-5HsHby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:02 INFO - Running setup.py (path:/tmp/pip-FeSpmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:47:02 INFO - Running setup.py (path:/tmp/pip-uC1IOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:47:02 INFO - Running setup.py (path:/tmp/pip-yq8fa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:47:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:47:02 INFO - Running setup.py install for manifestparser 08:47:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:47:02 INFO - Running setup.py install for mozcrash 08:47:02 INFO - Running setup.py install for mozdebug 08:47:03 INFO - Running setup.py install for mozdevice 08:47:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Running setup.py install for mozfile 08:47:03 INFO - Running setup.py install for mozhttpd 08:47:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Running setup.py install for mozinfo 08:47:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Running setup.py install for mozInstall 08:47:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Running setup.py install for mozleak 08:47:03 INFO - Running setup.py install for mozlog 08:47:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Running setup.py install for moznetwork 08:47:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:47:03 INFO - Running setup.py install for mozprocess 08:47:04 INFO - Running setup.py install for mozprofile 08:47:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:47:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:47:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:47:04 INFO - Running setup.py install for mozrunner 08:47:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:47:04 INFO - Running setup.py install for mozscreenshot 08:47:04 INFO - Running setup.py install for moztest 08:47:04 INFO - Running setup.py install for mozversion 08:47:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:47:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:47:04 INFO - Cleaning up... 08:47:04 INFO - Return code: 0 08:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:47:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:47:04 INFO - Using env: {'DISPLAY': ':0', 08:47:04 INFO - 'HOME': '/home/cltbld', 08:47:04 INFO - 'LANG': 'en_US.UTF-8', 08:47:04 INFO - 'LANGUAGE': 'en_US:en', 08:47:04 INFO - 'LOGNAME': 'cltbld', 08:47:04 INFO - 'MAIL': '/var/mail/cltbld', 08:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:04 INFO - 'MOZ_NO_REMOTE': '1', 08:47:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:04 INFO - 'NO_EM_RESTART': '1', 08:47:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:04 INFO - 'PWD': '/builds/slave/test', 08:47:04 INFO - 'SHELL': '/bin/bash', 08:47:04 INFO - 'SHLVL': '1', 08:47:04 INFO - 'TERM': 'linux', 08:47:04 INFO - 'TMOUT': '86400', 08:47:04 INFO - 'USER': 'cltbld', 08:47:04 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:04 INFO - '_': '/tools/buildbot/bin/python'} 08:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:47:04 INFO - Running setup.py (path:/tmp/pip-7Vl3od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:47:04 INFO - Running setup.py (path:/tmp/pip-jWDIpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:47:04 INFO - Running setup.py (path:/tmp/pip-cufpCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:47:04 INFO - Running setup.py (path:/tmp/pip-3k7EvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:47:05 INFO - Running setup.py (path:/tmp/pip-Tb9MeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:47:05 INFO - Running setup.py (path:/tmp/pip-Lz23nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:47:05 INFO - Running setup.py (path:/tmp/pip-8XpnYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:47:05 INFO - Running setup.py (path:/tmp/pip-e2xpDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:47:05 INFO - Running setup.py (path:/tmp/pip-uZjEDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:47:05 INFO - Running setup.py (path:/tmp/pip-LeSPjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:47:05 INFO - Running setup.py (path:/tmp/pip-cHU7Bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:47:05 INFO - Running setup.py (path:/tmp/pip-qmQI5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:47:05 INFO - Running setup.py (path:/tmp/pip-_iuew3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:47:05 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)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:47:05 INFO - Running setup.py (path:/tmp/pip-8sWP3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:05 INFO - Running setup.py (path:/tmp/pip-CGEj2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:47:05 INFO - Running setup.py (path:/tmp/pip-pcQsIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:47:05 INFO - Running setup.py (path:/tmp/pip-wke9iY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:47:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:08 INFO - Downloading blessings-1.6.tar.gz 08:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:47:08 INFO - Installing collected packages: blessings 08:47:08 INFO - Running setup.py install for blessings 08:47:08 INFO - Successfully installed blessings 08:47:08 INFO - Cleaning up... 08:47:08 INFO - Return code: 0 08:47:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:47:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:08 INFO - Reading from file tmpfile_stdout 08:47:08 INFO - Current package versions: 08:47:08 INFO - argparse == 1.2.1 08:47:08 INFO - blessings == 1.6 08:47:08 INFO - blobuploader == 1.2.4 08:47:08 INFO - docopt == 0.6.1 08:47:08 INFO - functools32 == 3.2.3-2 08:47:08 INFO - jsonschema == 2.5.1 08:47:08 INFO - manifestparser == 1.1 08:47:08 INFO - mozInstall == 1.12 08:47:08 INFO - mozcrash == 1.0 08:47:08 INFO - mozdebug == 0.1 08:47:08 INFO - mozdevice == 0.49 08:47:08 INFO - mozfile == 1.2 08:47:08 INFO - mozhttpd == 0.7 08:47:08 INFO - mozinfo == 0.9 08:47:08 INFO - mozleak == 0.1 08:47:08 INFO - mozlog == 3.4 08:47:08 INFO - moznetwork == 0.27 08:47:08 INFO - mozprocess == 0.25 08:47:08 INFO - mozprofile == 0.28 08:47:08 INFO - mozrunner == 6.13 08:47:08 INFO - mozscreenshot == 0.1 08:47:08 INFO - mozsystemmonitor == 0.3 08:47:08 INFO - moztest == 0.8 08:47:08 INFO - mozversion == 1.4 08:47:08 INFO - psutil == 3.1.1 08:47:08 INFO - requests == 1.2.3 08:47:08 INFO - wsgiref == 0.1.2 08:47:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:47:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:47:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:47:08 INFO - Using env: {'DISPLAY': ':0', 08:47:08 INFO - 'HOME': '/home/cltbld', 08:47:08 INFO - 'LANG': 'en_US.UTF-8', 08:47:08 INFO - 'LANGUAGE': 'en_US:en', 08:47:08 INFO - 'LOGNAME': 'cltbld', 08:47:08 INFO - 'MAIL': '/var/mail/cltbld', 08:47:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:08 INFO - 'MOZ_NO_REMOTE': '1', 08:47:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:08 INFO - 'NO_EM_RESTART': '1', 08:47:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:08 INFO - 'PWD': '/builds/slave/test', 08:47:08 INFO - 'SHELL': '/bin/bash', 08:47:08 INFO - 'SHLVL': '1', 08:47:08 INFO - 'TERM': 'linux', 08:47:08 INFO - 'TMOUT': '86400', 08:47:08 INFO - 'USER': 'cltbld', 08:47:08 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:47:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:08 INFO - '_': '/tools/buildbot/bin/python'} 08:47:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 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)) 08:47:08 INFO - Cleaning up... 08:47:08 INFO - Return code: 0 08:47:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:08 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 0x25371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2575a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, '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': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', '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 08:47:08 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 08:47:08 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 08:47:08 INFO - Using env: {'DISPLAY': ':0', 08:47:08 INFO - 'HOME': '/home/cltbld', 08:47:08 INFO - 'LANG': 'en_US.UTF-8', 08:47:08 INFO - 'LANGUAGE': 'en_US:en', 08:47:08 INFO - 'LOGNAME': 'cltbld', 08:47:08 INFO - 'MAIL': '/var/mail/cltbld', 08:47:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:08 INFO - 'MOZ_NO_REMOTE': '1', 08:47:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:08 INFO - 'NO_EM_RESTART': '1', 08:47:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:08 INFO - 'PWD': '/builds/slave/test', 08:47:08 INFO - 'SHELL': '/bin/bash', 08:47:08 INFO - 'SHLVL': '1', 08:47:08 INFO - 'TERM': 'linux', 08:47:08 INFO - 'TMOUT': '86400', 08:47:08 INFO - 'USER': 'cltbld', 08:47:08 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:47:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:08 INFO - '_': '/tools/buildbot/bin/python'} 08:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:47:09 INFO - Cleaning up... 08:47:09 INFO - Return code: 0 08:47:09 INFO - Running post-action listener: _resource_record_post_action 08:47:09 INFO - Running post-action listener: _start_resource_monitoring 08:47:09 INFO - Starting resource monitoring. 08:47:09 INFO - [mozharness: 2017-04-16 15:47:09.155292Z] Finished create-virtualenv step (success) 08:47:09 INFO - [mozharness: 2017-04-16 15:47:09.155754Z] Running install step. 08:47:09 INFO - Running pre-action listener: _resource_record_pre_action 08:47:09 INFO - Running main action method: install 08:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:09 INFO - Reading from file tmpfile_stdout 08:47:09 INFO - Detecting whether we're running mozinstall >=1.0... 08:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:47:09 INFO - Reading from file tmpfile_stdout 08:47:09 INFO - Output received: 08:47:09 INFO - Usage: mozinstall [options] installer 08:47:09 INFO - Options: 08:47:09 INFO - -h, --help show this help message and exit 08:47:09 INFO - -d DEST, --destination=DEST 08:47:09 INFO - Directory to install application into. [default: 08:47:09 INFO - "/builds/slave/test"] 08:47:09 INFO - --app=APP Application being installed. [default: firefox] 08:47:09 INFO - mkdir: /builds/slave/test/build/application 08:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:47:26 INFO - Reading from file tmpfile_stdout 08:47:26 INFO - Output received: 08:47:26 INFO - /builds/slave/test/build/application/firefox/firefox 08:47:26 INFO - Running post-action listener: _resource_record_post_action 08:47:26 INFO - [mozharness: 2017-04-16 15:47:26.116033Z] Finished install step (success) 08:47:26 INFO - [mozharness: 2017-04-16 15:47:26.116304Z] Running run-tests step. 08:47:26 INFO - Running pre-action listener: _resource_record_pre_action 08:47:26 INFO - Running main action method: run_tests 08:47:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:47:26 INFO - Python 2.7.3 08:47:26 INFO - Return code: 0 08:47:26 INFO - grabbing minidump binary from tooltool 08:47: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')]} 08:47:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d2400>, '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 0x27d33b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27d3840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:47:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:47:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:47:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:47:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:47:26 INFO - Return code: 0 08:47:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:47:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:47:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:47:26 INFO - ENV: RUST_BACKTRACE is now 1 08:47:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:47:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:47:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--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 08:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-080 --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 08:47:26 INFO - Using env: {'DISPLAY': ':0', 08:47:26 INFO - 'HOME': '/home/cltbld', 08:47:26 INFO - 'LANG': 'en_US.UTF-8', 08:47:26 INFO - 'LANGUAGE': 'en_US:en', 08:47:26 INFO - 'LOGNAME': 'cltbld', 08:47:26 INFO - 'MAIL': '/var/mail/cltbld', 08:47:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:47:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:26 INFO - 'MOZ_NO_REMOTE': '1', 08:47:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:47:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:26 INFO - 'NO_EM_RESTART': '1', 08:47:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:26 INFO - 'PWD': '/builds/slave/test', 08:47:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:47:26 INFO - 'RUST_BACKTRACE': '1', 08:47:26 INFO - 'SHELL': '/bin/bash', 08:47:26 INFO - 'SHLVL': '1', 08:47:26 INFO - 'TERM': 'linux', 08:47:26 INFO - 'TMOUT': '86400', 08:47:26 INFO - 'USER': 'cltbld', 08:47:26 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748', 08:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:26 INFO - '_': '/tools/buildbot/bin/python'} 08:47:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--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 08:47:26 INFO - mozversion application_buildid: 20170416145831 08:47:26 INFO - mozversion application_changeset: 2ee12fbb228baa2b0548736fa79823246cbf95fa 08:47:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:47:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:47:26 INFO - mozversion application_name: Firefox 08:47:26 INFO - mozversion application_remotingname: firefox-dev 08:47:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:47:26 INFO - mozversion application_vendor: Mozilla 08:47:26 INFO - mozversion application_version: 54.0a2 08:47:26 INFO - mozversion platform_buildid: 20170416145831 08:47:26 INFO - mozversion platform_changeset: 2ee12fbb228baa2b0548736fa79823246cbf95fa 08:47:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:47:26 INFO - mozversion platform_version: 54.0a2 08:47:26 INFO - using testdate: 1492357646 08:47:26 INFO - actual date: 1492357646 08:47:26 INFO - starting webserver on 'localhost:47608' 08:47:26 INFO - SUITE-START | Running 2 tests 08:47:26 INFO - TEST-START | dromaeo_css 08:47:26 INFO - operating with platform_type : linux_ 08:47:26 INFO - Initialising browser for dromaeo_css test... 08:47:26 INFO - TEST-INFO | started process 3487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNFQxN3/profile http://localhost:47608/getInfo.html) 08:47:32 INFO - PROCESS | 3487 | __metrics Screen width/height:1600/1200 08:47:33 INFO - PROCESS | 3487 | colorDepth:24 08:47:33 INFO - PROCESS | 3487 | Browser inner width/height: 1024/697 08:47:33 INFO - PROCESS | 3487 | __metrics 08:47:34 INFO - PROCESS | 3487 | 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" 08:47:34 INFO - PROCESS | 3487 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:47:34 INFO - PROCESS | 3487 | console.error: PushService: 08:47:34 INFO - PROCESS | 3487 | stateChangeProcessEnqueue: Error transitioning state 08:47:34 INFO - PROCESS | 3487 | UnknownError 08:47:35 INFO - TEST-INFO | 3487: exit 0 08:47:35 INFO - Browser initialized. 08:47:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:47:35 INFO - TEST-INFO | started process 3580 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNFQxN3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:47 INFO - PROCESS | 3580 | RSS: Main: 139870208 08:47:47 INFO - PROCESS | 3580 | 08:50:49 INFO - PROCESS | 3580 | Cycle 1(1): loaded http://localhost:47608/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47608/tests/dromaeo/cssquery-ext.html) 08:50:50 INFO - PROCESS | 3580 | RSS: Main: 243351552 08:50:50 INFO - PROCESS | 3580 | 08:52:37 INFO - PROCESS | 3580 | ************************* 08:52:37 INFO - PROCESS | 3580 | A coding exception was thrown and uncaught in a Task. 08:52:37 INFO - PROCESS | 3580 | 08:52:37 ERROR - PROCESS | 3580 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:52:37 INFO - PROCESS | 3580 | Full stack: 08:52:37 INFO - PROCESS | 3580 | ************************* 08:52:37 INFO - PROCESS | 3580 | ************************* 08:52:37 INFO - PROCESS | 3580 | A coding exception was thrown and uncaught in a Task. 08:52:37 INFO - PROCESS | 3580 | 08:52:37 ERROR - PROCESS | 3580 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:52:37 INFO - PROCESS | 3580 | Full stack: 08:52:37 INFO - PROCESS | 3580 | ************************* 08:52:37 INFO - PROCESS | 3580 | ************************* 08:52:37 INFO - PROCESS | 3580 | A coding exception was thrown and uncaught in a Task. 08:52:37 INFO - PROCESS | 3580 | 08:52:37 ERROR - PROCESS | 3580 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:52:37 INFO - PROCESS | 3580 | Full stack: 08:52:37 INFO - PROCESS | 3580 | ************************* 08:52:37 INFO - PROCESS | 3580 | ************************* 08:52:37 INFO - PROCESS | 3580 | A coding exception was thrown and uncaught in a Task. 08:52:37 INFO - PROCESS | 3580 | 08:52:37 ERROR - PROCESS | 3580 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:52:37 INFO - PROCESS | 3580 | Full stack: 08:52:37 INFO - PROCESS | 3580 | ************************* 08:53:53 INFO - PROCESS | 3580 | Cycle 1(1): loaded http://localhost:47608/tests/dromaeo/cssquery-ext.html (next: http://localhost:47608/tests/dromaeo/cssquery-jquery.html) 08:53:53 INFO - PROCESS | 3580 | RSS: Main: 225824768 08:53:53 INFO - PROCESS | 3580 | 08:56:55 INFO - PROCESS | 3580 | Cycle 1(1): loaded http://localhost:47608/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47608/tests/dromaeo/cssquery-mootools.html) 08:56:56 INFO - PROCESS | 3580 | RSS: Main: 169115648 08:56:56 INFO - PROCESS | 3580 | 08:59:58 INFO - PROCESS | 3580 | Cycle 1(1): loaded http://localhost:47608/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47608/tests/dromaeo/cssquery-prototype.html) 08:59:58 INFO - PROCESS | 3580 | RSS: Main: 170627072 08:59:58 INFO - PROCESS | 3580 | 09:03:01 INFO - PROCESS | 3580 | Cycle 1(1): loaded http://localhost:47608/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47608/tests/dromaeo/cssquery-yui.html) 09:03:02 INFO - PROCESS | 3580 | RSS: Main: 177614848 09:03:02 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | Cycle 1(1): loaded http://localhost:47608/tests/dromaeo/cssquery-yui.html (next: http://localhost:47608/tests/dromaeo/cssquery-dojo.html) 09:06:04 INFO - PROCESS | 3580 | RSS: Main: 269340672 09:06:04 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | __start_tp_report 09:06:04 INFO - PROCESS | 3580 | _x_x_mozilla_page_load 09:06:04 INFO - PROCESS | 3580 | _x_x_mozilla_page_load_details 09:06:04 INFO - PROCESS | 3580 | |i|pagename|runs| 09:06:04 INFO - PROCESS | 3580 | |0;dojo.html;3391.5421418108554;3413.1808365992156;3415.4193787056183;3433.5364725762015;3458.273762509873;5755.7697692092315;5789.942100578993;5802.883942321151;5829.329627092886;5831.067029275317;5904.350521442638;5968.641881487114;5970.014947533663;5972.283326000875;5977.731002104905;5336.066188417021;5365.03429382711;5405.4324295948945;5406.351237851443;5495.093309603911;5234.8691282717855;5283.947160528384;5465.043617366965;5471.972640136813;5473.863153421176;5292.285541451913;5339.9733001335135;5353.973230133844;5364.570834333244;5400.270963419947;5361.678299302054;5385.78456861725;5389.892202155974;5397.27136836526;5406.02691515529;5335.1197052486295;5398.109311963522;5404.0272750905215;5413.350397952231;5416.187571864212;5961.940380596182;5991.6405015698765;5992.161097446361;5998.040313549811;5998.1002849572915;6123.953766440822;6130.049842274455;6375.2987171411105;6376.776812811529;6386.552941294111;6248.625082495065;6326.65203413812;6408.51936104794;6418.454431373339;6437.066625339299;5949.4751046813935;5954.523638108941;5955.404459554054;5963.016102343152;5963.701814909237;6459.09572659828;6490.578112422678;6493.642849643315;6495.707693153725;6504.05691174777;5943.55423343251;5975.312839023568;6102.115193296947;6121.265448146251;6145.217886812774;6247.187865577446;6297.2128483939505;6299.5275354348605;6302;6304.779332723333;6227.439530442282;6242.781502647385;6281.466075383552;6288.968555157195;6291.339409361951;6048.909266361008;6112.083187521907;6198.411150940653;6235.532335074888;6332.208473940757;4687.601553867891;4823.517648235148;4824.8793780155775;4831.9275210871865;4854.466008739036;4191.350340697197;4196.496420429568;4210.7473551587;4227.070044590185;4229.238737027303;6013.548983826143;6014.849628759316;6064.605800622945;6070.3929607038945;6070.969645151746;6110.111264678734;6154.1999540059505;6242.687865606701;6367.108604795332;6375.043743438521;6085.939140608537;6086.573939824259;6088.6042407243385;6091.177691011745;6131.386861313833;5367.980083784147;5375.838724838261;5376.247325374451;5378.731063446812;5382.703951282717;6342.39747224013;6394.872102558016;6567.146270984741;6570.244421891448;6581.078648989145;6244.656543889948;6247.687615619292;6347.39699728525;6387.968060159669;6388.648624325707;6538.8365290868105;6546.854300497299;6549.7380104795275;6555.80332590023;6559.147310849559;6151.6924153793025;6271;6272.686365681789;6273.560850740404;6277.246730392382;6130.295016073209;6141.087130295657;6262.279837818709;6313.873722525432;6398.168238129014;6247.562670613013;6248.718807653576;6251.749930002749;6256.343083976299;6256.374362563707;6187.690615469299;6202.906956395748;6231.096491008688;6234.875302494015;6242.906356404748;5983.162357270073;6053.122297266959;6099.542534309856;6114.052321890114;6234.127222188807;6234.9688251558455;6255.999040153553;6274.121622972696;6280.905786413299;6281.905771413341;5882.205902203148;5911.817636472809;6075.513958883367;6117.694115294306;6127.662978536223 09:06:04 INFO - PROCESS | 3580 | |1;ext.html;755.5089192025183;797.1828875402713;816.5631387207843;884.929205663547;896.4486840593037;30339.241518962037;43182;44858;44860.55139448604;44886.10227795442;44622;44779.328310075296;44818;44971.55028449715;44977.325340119925;7539.509931854439;7699.076110866704;9047.45715257085;9132.817343653123;9183.908160918405;10036.096751292382;10277.640282590111;10285.33145345554;10286.588536458532;10633.043026127647;10332.896671033268;10401.323913945569;10412.739681507946;10414.375137491737;10421.114205292559;10326.328788628753;10341.172706183488;10354.06813386795;10357.741056473573;10359.32664376813;10415.479226038668;10460.843087353696;10526.473676316153;10529.210309226839;10556.736081597945;41716;41738.58261417377;41837.37243941343;41886.162276754294;41892;4106.7946602670045;4122.855700050483;4161.52142503613;4225.471816022997;4249.2776228041;29250.707492925223;29284.8535757322;29310.85344573285;29369.853150734325;29839.25401864949;4191.91616167678;4263.317869140954;4305.483341998949;4322.675799315032;4376.409184760048;229543;229886;231054;231315.8434207835;231549;53051;53103.468965310625;53232.467675323525;53293.46706532963;53320;55093;55257.447425526036;55261;55539.72230138904;55554.722226389415;15709.45016924402;15964.36142554308;15985.60035999086;15985.840141598668;16000;11220.887791122148;11361.602343917923;13821.032527723164;13909.721805563831;13918.58244252674;13272.27002514871;13314.134581252187;13412.725791049834;13415.731685366238;13500.527481538296;5236.905237904793;5250.7374631268285;5277.419483856773;5322.467753224724;5390.595705322038;1006.6426418621284;1007.773251018515;1008.7528555503949;1018.3177271228295;1027.4503140819775;27628.723712763018;27753.444931101265;28073.298167545567;28187;28308.575371369447;1066.5253961986996;1070.9785804284027;1074.5379486820586;1084.728817795551;1102.2339474065448;14557.344919478648;14903;14918;15028.849711502964;15068.773968390484;296024;299351;300549;300683;301592;54839.177412338846;54998.90002200014;55178.44821551813;55245.723771380886;55351;1471.0879254862054;1503.1131632336974;1520.931558079889;1543.8456154384696;1545.1424459424902;25312.24063278031;26525.204243872715;26837.463250734487;26930.59604105901;27133.050343237894;41068;41107.383389248585;41259.38110928278;41319.7934010328;41383.586164137974;255876;256496;256711;257946;258316;29268.853655731586;29341.559876602296;35088;35223;35229;41699.58300416957;42626.36060459157;42678.78660606677;42719.35920961126;43652.56347436485;32984.505232421056;33240.1689957753;33352.66647333495;33622.83188584042;34190.82904585461;32792.67207327896;33000.504992424656;33180.34098295027;33329;33402.16494587558;37284;37312.813435933735;37337.626623734504;37640.43539346857;38384.42423363707;31719.207019823767;31863.522047168848;31865.681343186272;31916.84041579777;32177.195570110933;24392.51214975655;25218.24345269645;26887.596686050118;27050.188494345202;27318.453630927666 09:06:04 INFO - PROCESS | 3580 | |2;jquery.html;418.305612682946;467.61889060415757;469.70173939548374;484.6098890393234;484.65105124310486;18768.90615546922;18980.62038759224;20477.38567842965;20518.89740551295;20658;20808.687869681966;21081.789182108176;21212.469688257803;21423.250186243484;21863;6573.572717773353;6808;6859.348361905636;6862.073620061277;7339.119305683325;7609.124950630665;7694.923050769505;7705.6917723291;7712.691492340327;7714.421418393643;7570.659320330598;7675.69297228113;7811.843763124734;7814.609269536529;7820.569868657221;7567.356774674159;7729.149793522708;7741.187175346592;7775.883361749578;7780.805479862992;7263.382612477946;7476.514026588255;7480.513766605153;7489.775306740807;7492.213317601628;25658.230253092435;25780.8710956444;25915.611265831027;26224.475510489876;26345.3413664658;3184.4528467532095;3202.2794871154038;3582.1223800168864;3639.6724294813594;3734.458503517002;15157.696846063127;15756.606084847856;15824.920875395665;15969;16076.115813630246;2229.843910926236;2284.7144106986625;2315.351701523576;2354.399628094842;2357.988210058939;104534.47732761364;106134.46932765287;106253;106339;108037.45981270043;14124.312806922288;15972.20138993069;18028.54928626795;18068.728969065476;18136.63726725458;31501.8424907874;31528.527072093937;31532.684673152973;31578.210544736106;32322;10069.244806639532;10119.392836429837;10152.644657437102;10211.336263142872;10308.12380947628;8467.576621168917;8496.617652205665;8507.447015943944;8670.91329086701;8698.652053917898;9121.133492318218;9140.360174787835;9181.540922953825;9324.766880827898;9371.906280937103;3682.963170368262;3725.850965961331;3801.4867992820614;3814.389697648363;3883.3980732986433;951.3388195204267;1088.4068182840372;1186.649938268213;1187.4300335838846;1208.3535308610028;20136.494898927744;20388.79611203899;20442.591148176954;20486.282980095624;20758.273460429114;1161.7095726068483;1174.7444905780922;1186.9762604747857;1190.4285942747356;1215.6960759810047;10762.300450470695;10966.506507207196;11128.332300062022;11172.385518796544;11263.155263355282;601318;605737;647270;680351;695625;117411.41294293644;118640.40679796546;119824;120777;123432.38283808703;1252.3863306979515;1259.2381609126346;1295.4429595273934;1312.4881296294452;1336.4253371050347;14655;19201.423957281302;19298.7105193425;19299.903500482407;19378.321758738377;51602.74198628983;51750.741246293524;51797.741011294704;52017.21974170466;52183.73908130435;62119.37880621136;62547;63080;63633.36366636274;65363.67318163569;11431.371274579978;11671.416429178338;11672.474738636613;11816.940915295369;11985.820212696643;12965;13642.386092626055;13899.444022238997;14100.153990760587;14144.646383840487;11137.498812553293;11364.659060228207;11460.541578336773;11880.34658093813;11946.880531194576;11541.769164616828;11574.131940104357;11620;11780.882191177978;11804;12050.75898482043;12256.38718064111;12305.077119215915;12448.564300249447;12573.811392828931;12144.453499592362;12208.75582488363;12354.75290494203;12359.443825028075;12391.938040310102;11600.767984640428;12324.198927069712;12441.93779031099;12569.805868442483;12683.11218214716 09:06:04 INFO - PROCESS | 3580 | |3;mootools.html;1547.9690406191876;1561.3598424645897;1565.5459916624177;1577.2902194012693;1593.1715507935867;10169.084782369586;10849.240553161291;10850.24048316618;10853.51159197836;10857.837132442999;10392.324498907567;10531.63139290125;10602.734931626714;10638.095761860232;10642.467876606177;2394.3774618599114;2408.3738228060743;2431.987840060797;2434.8295619306655;2435.1720415058876;2336.906523739048;2390.3546042568496;2410.0359856057544;2410.963835542468;2412.9155479558217;2362.3503536527505;2427.1141033522813;2430.7812296893276;2434.4522482441484;2439.5730747119183;2252.6395776675654;2329.7204335479764;2333.5099629077913;2334.7898689117974;2339.2982105368405;2221.900159421081;2226.109556177526;2232.3191426614876;2232.79904808568;2232.810211132056;10771.83842242367;10884.292520986111;10894.836577451344;10908.69095708512;10918;5635.070213414781;5789.855253618651;5793.131030345482;5795.73919173634;5801.100829371454;3454.2055327274866;3549.4853246279404;3574.6067932527403;3578.9284214315567;3647.9817600911824;1232.7472868062116;1245.1657389549023;1254.4417734108326;1283.4481173095564;1327.071050264809;15182;15544.222788860512;16040.598985025352;16221.432249871315;16325.755113673304;4549.772511374485;4606.424196975378;4756.9286460703115;4757.167495688241;4758.714477131383;10889.292196007234;11108.666739997812;12405.44175512104;12409.937950310372;12434.129610927148;5673.971630141906;5851.6781577012825;5945.732442040118;5958.76164953397;5964.194833697482;5388.676679399248;5412.729363531807;5530.889382212334;5535.308086489189;5537.612367134262;4285.142971405731;4290.935635965463;4373.343998400202;4406;4413.757243351646;1156.6876943225282;1191.839101721274;1204.235310577772;1204.8253003314471;1205.7166565857017;1000.5797565022708;1007.2697294461457;1023.9641612543526;1027.1166796554958;1030.4899074957943;6675.699593518303;6749.257581666013;6770.255271920085;6870.27862074485;6919.169699635975;1019.2378653848007;1057.5135437698746;1063.9095676867398;1076.7738774857369;1093.3822390349585;4114.773687447159;4128.2156390285745;4139.234241665302;4224.218519573888;4282.957170428318;14393.28033598316;14835.332410041574;14835.703285934436;14941.551753447415;14945.551633450796;14333.928330358282;14472;14760.63098422548;14814.259287035606;14845.331960061607;10708.73228169302;10709.78580428387;10757.462126893624;10826.566937322576;10896.564137434572;6585.209774827052;6620.933790662032;6649;6659.367360100783;6668.933310667026;12789.872101278868;12889.355532223539;12905.161164524276;12908.741825163632;13243.536476223286;13130.803037954629;13192.340382980621;13227.80158297607;13326.600201993955;13339.266340351374;8336.45813022176;8953.910460895308;9203.76990575241;9313.34806563534;9322.254219662274;10522.526486307981;10525.526351314362;10571.471426428801;10630.893691062991;10642.574297028032;8920.375573709778;8944.105589441004;9087.954560227421;9312.674056407994;9316.44101353921;8672.653093876173;8790.164934331227;8806.60370283326;8827.382083254111;9081.091890810865;7880;7961.16407777175;7984.281414672475;8027.157148499324;8034.718784842503;5698.601097923106;5711.057675483498;5766.711664416846;5791;5792.0443126885275;5712.37163911961;5752.511036561772;5759.2512973313205;5823.35943046273;5848.619839710405 09:06:04 INFO - PROCESS | 3580 | |4;prototype.html;422.1915032712356;430.50706940553056;434.41788004074533;441.8166460918717;442.46903715541356;5168.664036837612;5263.9736801315985;5342.652727572705;5379.919301210485;5396.568274538037;5213.400458947222;5220;5250.976059668366;5251.632385733;5274.525292723654;4305.892352691192;4448.221561226788;4454.175977444183;4456.79944402501;4466.8883277917985;4459;4464.6428285737065;4517.41273634427;4536.206163921317;4539.6141327987;4518.751468669222;4524.163029839474;4526.592606665399;4547.18150732868;4563.5664611861985;4422.867313980586;4456.754878481682;4485.865424037284;4492.775361231942;4507.233770259064;4206.810693518768;4224.331389101288;4235.809388577491;4245.27830268855;4248.277792775236;5110;5194.870128246786;5195.181686409765;5215.217717342429;5223.817166399158;4844.781984810692;5065.746712664352;5126.5642420394315;5130.204818253177;5134.1773670346865;5449.89356067042;5483.396826349099;5526;5529.419410961871;5540.833774986717;4603.700759450625;4619.445666520006;4628.722276663411;4667.206574882278;4671.743054132022;5373.489518495737;5487.341519017704;5587.580931430159;5705.0635061807225;5714.79998200061;4579.175748365326;4642.675012749142;4687.20317546018;4751.548602882721;4767.4994125617;4828.179209534387;4832.565069143794;4837.830675926396;4847.175980083394;4849.4908034656555;4804.543568361;4879;5105.974470127626;5115.0792857285305;5125.231215317657;5511.779528818803;5512.173174023928;5512.889742205213;5524.66851988882;5568.914061757919;1638.7463590353389;1660.9335626574803;1670.223346144049;1670.874684398675;1676.8239334869663;1235.2773627427875;1279.5969269680022;1297.351324337831;1301.6615679923289;1319.2678063674562;5366.731663416814;5420;5471.890562188734;5495.862603434946;5516;5612.85967850807;5650.7174641267775;5673.74468148926;5709.773977702668;5779.306483221956;5083.415407228142;5422.539084177889;5461.590380721462;5461.853010867762;5462.808801691922;3004.8046876952926;3017.562453444238;3028.727414532703;3031.4240294344004;3031.863566139529;5575.721213939287;5878.588498805043;5884.264466941632;5889.527618095476;5909.5863289569315;5839.043920286752;5846.386129456346;5855.677937713466;5862.677552734641;5867.29848343984;5897.616654917502;5902.232709747877;5904.734286957183;5942.4549617099865;5957.527626035234;5432.972835135799;5664.479306708475;5692.5730570208225;5722;5728.226689396878;5669.007923613269;5673.404292549223;5676.801311954062;5682.403347648437;5718.857028574319;5562.916556251578;5659.0193432298365;5721.828345149652;5805.216295800013;5840.883182336244;4737.810487580458;4754.263089221176;4765.475797662324;4774.3315935769715;4784.856454306377;5473.564263902525;5648.887022259614;5707.229524014371;5712.171735098471;5737.713114344349;5477.863053423696;5490.670559766427;5503.229547863222;5510.043934403229;5527.44725527444;5449.264349312792;5460.890782184255;5519.724013799214;5590.692511911721;5602.775888964396;5382.838514844561;5482.23248745184;5504.394516603091;5520.641158324696;5547.306586676666;5530.142827861688;5534.640248384003;5548.167774833646;5556.249906262603;5587.497125258747;5430.864228394322;5433.157860531624;5444.972775136099;5493.423190564933;5540.697935985121 09:06:04 INFO - PROCESS | 3580 | |5;yui.html;810.7162493127403;833.6082041440528;865.5931712095315;875.7066382761775;881.1628952495128;16760;17044.31822727091;17072.317107315692;17310.653786924253;17328.133593320348;16494.75257871133;16716.41492547761;16729;16744.83255167448;16749.91625041873;832.097174565597;856.7044369692446;862.8188080503102;862.9956850215772;863.5078005536847;863.4301361101674;866.263675875504;866.4368160695535;867.2714919467646;869.1091631078132;921.889373275208;922.4972390047441;922.6263363337825;923.0123767568704;923.0492592629603;914.862770584411;917.9954100229523;918.274563095154;918.7243826851918;918.9264858811279;676.0805304785488;676.7834293026234;676.8781619308522;677.3362105136994;677.4614181725491;16489.257983390777;16656.41702540405;16805.83194168055;18116.90941545297;18118.818811811845;15873.841261587351;16233;16333.83666163335;16408.507744767558;16632.750508742378;13347.933260333637;13698.452061917511;13729.382177802023;13743.862561374459;13827.654308642264;4019.738716983416;4052.4731784867777;4054.148628787958;4058.5738497457933;4129.504459464883;88488.55755721245;88815.55592221998;89580;89822.55088724515;90170;11545.134114941413;11739.119566032581;11751.412429378497;11773.293602383885;12087.033037357167;23993.760062399153;24236.515269694508;24355.512889742105;24543.63184552233;24572.262832115066;8501.48991060526;8604.698835540852;8639.395242332976;8641;8859.3798434109;4887.877803054881;4983.700977941335;4984.277279794482;5107.361579802525;5185.662931909414;3695;3744.719146064056;3755.004923695223;3762.266358060152;3803.1633040730994;596.3589141672633;596.686739461786;603.6227357901312;610.3499772742016;616.7563812294119;792.397777688949;804.1797366686064;814.8510600053895;835.1773503099442;835.6197929941862;10906.400147991937;11279.26684765504;11283.210175287815;12160.14878958482;12186.939065304794;648.6367634124905;660.9438197753149;668.9899651505232;670.5875886329871;670.963097029668;5015.448300686921;5354.759035843396;5357.330333708286;5418.864528386822;5428.294321738149;79373;79440.6027969868;82243.58878205571;82358;85018.57490712508;70212.64893675601;70922.64538677377;71413;72148;73097.26902730904;62727.6863615679;64011.67994160092;65021.674891626186;66338.66830665816;67529.9870501943;9962.800743984935;10191.439470829175;10211.876693563554;10271.126954208976;10322.845157322496;44299.33550996802;44596.33105503355;44896.77551612221;45047.77476112729;45318.32022519599;50085.24872126994;50155.74922125366;50794.23808642799;50802;52752.20871686851;12349.629511114681;12581.811272830731;12811.359432028175;12864.935675321563;12950.352482376033;13685.11046781956;13868;13998.160110393408;14021.929890350482;14137.717245654823;12530.886338274871;12609.684757881127;12720;12788.552400665933;12807.231566106064;13540.661483462993;13597.79603305971;13615.52345667897;13866.584002479942;13896;13685.247311397969;13950.930245348709;14008.369623366769;14056.297185140907;14113.576592702235;10265.281430299807;10301.227407944285;10555.943010859697;10758.626189325343;10865.565377385132;11179.10567154642;11288.2662626929;11400.714982125513;11431.942840285745;11476.942615286871 09:06:04 INFO - PROCESS | 3580 | __end_tp_report 09:06:04 INFO - PROCESS | 3580 | __start_cc_report 09:06:04 INFO - PROCESS | 3580 | _x_x_mozilla_cycle_collect,1159 09:06:04 INFO - PROCESS | 3580 | __end_cc_report 09:06:04 INFO - PROCESS | 3580 | __startTimestamp1492358764506__endTimestamp 09:06:04 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | ------- Summary: start ------- 09:06:04 INFO - PROCESS | 3580 | Number of tests: 6 09:06:04 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | [#0] dojo.html Cycles:165 Average:5863.26 Median:6099.54 stddev:671.52 (11.0%) stddev-sans-first:644.98 09:06:04 INFO - PROCESS | 3580 | Values: 3391.5 3413.2 3415.4 3433.5 3458.3 5755.8 5789.9 5802.9 5829.3 5831.1 5904.4 5968.6 5970.0 5972.3 5977.7 5336.1 5365.0 5405.4 5406.4 5495.1 5234.9 5283.9 5465.0 5472.0 5473.9 5292.3 5340.0 5354.0 5364.6 5400.3 5361.7 5385.8 5389.9 5397.3 5406.0 5335.1 5398.1 5404.0 5413.4 5416.2 5961.9 5991.6 5992.2 5998.0 5998.1 6124.0 6130.0 6375.3 6376.8 6386.6 6248.6 6326.7 6408.5 6418.5 6437.1 5949.5 5954.5 5955.4 5963.0 5963.7 6459.1 6490.6 6493.6 6495.7 6504.1 5943.6 5975.3 6102.1 6121.3 6145.2 6247.2 6297.2 6299.5 6302.0 6304.8 6227.4 6242.8 6281.5 6289.0 6291.3 6048.9 6112.1 6198.4 6235.5 6332.2 4687.6 4823.5 4824.9 4831.9 4854.5 4191.4 4196.5 4210.7 4227.1 4229.2 6013.5 6014.8 6064.6 6070.4 6071.0 6110.1 6154.2 6242.7 6367.1 6375.0 6085.9 6086.6 6088.6 6091.2 6131.4 5368.0 5375.8 5376.2 5378.7 5382.7 6342.4 6394.9 6567.1 6570.2 6581.1 6244.7 6247.7 6347.4 6388.0 6388.6 6538.8 6546.9 6549.7 6555.8 6559.1 6151.7 6271.0 6272.7 6273.6 6277.2 6130.3 6141.1 6262.3 6313.9 6398.2 6247.6 6248.7 6251.7 6256.3 6256.4 6187.7 6202.9 6231.1 6234.9 6242.9 5983.2 6053.1 6099.5 6114.1 6234.1 6235.0 6256.0 6274.1 6280.9 6281.9 5882.2 5911.8 6075.5 6117.7 6127.7 09:06:04 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | [#1] ext.html Cycles:180 Average:43544.76 Median:301137.50 stddev:68700.92 (22.8%) stddev-sans-first:68818.52 09:06:04 INFO - PROCESS | 3580 | Values: 755.5 797.2 816.6 884.9 896.4 30339.2 43182.0 44858.0 44860.6 44886.1 44622.0 44779.3 44818.0 44971.6 44977.3 7539.5 7699.1 9047.5 9132.8 9183.9 10036.1 10277.6 10285.3 10286.6 10633.0 10332.9 10401.3 10412.7 10414.4 10421.1 10326.3 10341.2 10354.1 10357.7 10359.3 10415.5 10460.8 10526.5 10529.2 10556.7 41716.0 41738.6 41837.4 41886.2 41892.0 4106.8 4122.9 4161.5 4225.5 4249.3 29250.7 29284.9 29310.9 29369.9 29839.3 4191.9 4263.3 4305.5 4322.7 4376.4 229543.0 229886.0 231054.0 231315.8 231549.0 53051.0 53103.5 53232.5 53293.5 53320.0 55093.0 55257.4 55261.0 55539.7 55554.7 15709.5 15964.4 15985.6 15985.8 16000.0 11220.9 11361.6 13821.0 13909.7 13918.6 13272.3 13314.1 13412.7 13415.7 13500.5 5236.9 5250.7 5277.4 5322.5 5390.6 1006.6 1007.8 1008.8 1018.3 1027.5 27628.7 27753.4 28073.3 28187.0 28308.6 1066.5 1071.0 1074.5 1084.7 1102.2 14557.3 14903.0 14918.0 15028.8 15068.8 296024.0 299351.0 300549.0 300683.0 301592.0 54839.2 54998.9 55178.4 55245.7 55351.0 1471.1 1503.1 1520.9 1543.8 1545.1 25312.2 26525.2 26837.5 26930.6 27133.1 41068.0 41107.4 41259.4 41319.8 41383.6 255876.0 256496.0 256711.0 257946.0 258316.0 29268.9 29341.6 35088.0 35223.0 35229.0 41699.6 42626.4 42678.8 42719.4 43652.6 32984.5 33240.2 33352.7 33622.8 34190.8 32792.7 33000.5 33180.3 33329.0 33402.2 37284.0 37312.8 37337.6 37640.4 38384.4 31719.2 31863.5 31865.7 31916.8 32177.2 24392.5 25218.2 26887.6 27050.2 27318.5 09:06:04 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | [#2] jquery.html Cycles:180 Average:37043.92 Median:20502.59 stddev:106680.08 (520.3%) stddev-sans-first:106943.90 09:06:04 INFO - PROCESS | 3580 | Values: 418.3 467.6 469.7 484.6 484.7 18768.9 18980.6 20477.4 20518.9 20658.0 20808.7 21081.8 21212.5 21423.3 21863.0 6573.6 6808.0 6859.3 6862.1 7339.1 7609.1 7694.9 7705.7 7712.7 7714.4 7570.7 7675.7 7811.8 7814.6 7820.6 7567.4 7729.1 7741.2 7775.9 7780.8 7263.4 7476.5 7480.5 7489.8 7492.2 25658.2 25780.9 25915.6 26224.5 26345.3 3184.5 3202.3 3582.1 3639.7 3734.5 15157.7 15756.6 15824.9 15969.0 16076.1 2229.8 2284.7 2315.4 2354.4 2358.0 104534.5 106134.5 106253.0 106339.0 108037.5 14124.3 15972.2 18028.5 18068.7 18136.6 31501.8 31528.5 31532.7 31578.2 32322.0 10069.2 10119.4 10152.6 10211.3 10308.1 8467.6 8496.6 8507.4 8670.9 8698.7 9121.1 9140.4 9181.5 9324.8 9371.9 3683.0 3725.9 3801.5 3814.4 3883.4 951.3 1088.4 1186.6 1187.4 1208.4 20136.5 20388.8 20442.6 20486.3 20758.3 1161.7 1174.7 1187.0 1190.4 1215.7 10762.3 10966.5 11128.3 11172.4 11263.2 601318.0 605737.0 647270.0 680351.0 695625.0 117411.4 118640.4 119824.0 120777.0 123432.4 1252.4 1259.2 1295.4 1312.5 1336.4 14655.0 19201.4 19298.7 19299.9 19378.3 51602.7 51750.7 51797.7 52017.2 52183.7 62119.4 62547.0 63080.0 63633.4 65363.7 11431.4 11671.4 11672.5 11816.9 11985.8 12965.0 13642.4 13899.4 14100.2 14144.6 11137.5 11364.7 11460.5 11880.3 11946.9 11541.8 11574.1 11620.0 11780.9 11804.0 12050.8 12256.4 12305.1 12448.6 12573.8 12144.5 12208.8 12354.8 12359.4 12391.9 11600.8 12324.2 12441.9 12569.8 12683.1 09:06:04 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | [#3] mootools.html Cycles:180 Average:6904.47 Median:2350.82 stddev:4400.72 (187.2%) stddev-sans-first:4394.66 09:06:04 INFO - PROCESS | 3580 | Values: 1548.0 1561.4 1565.5 1577.3 1593.2 10169.1 10849.2 10850.2 10853.5 10857.8 10392.3 10531.6 10602.7 10638.1 10642.5 2394.4 2408.4 2432.0 2434.8 2435.2 2336.9 2390.4 2410.0 2411.0 2412.9 2362.4 2427.1 2430.8 2434.5 2439.6 2252.6 2329.7 2333.5 2334.8 2339.3 2221.9 2226.1 2232.3 2232.8 2232.8 10771.8 10884.3 10894.8 10908.7 10918.0 5635.1 5789.9 5793.1 5795.7 5801.1 3454.2 3549.5 3574.6 3578.9 3648.0 1232.7 1245.2 1254.4 1283.4 1327.1 15182.0 15544.2 16040.6 16221.4 16325.8 4549.8 4606.4 4756.9 4757.2 4758.7 10889.3 11108.7 12405.4 12409.9 12434.1 5674.0 5851.7 5945.7 5958.8 5964.2 5388.7 5412.7 5530.9 5535.3 5537.6 4285.1 4290.9 4373.3 4406.0 4413.8 1156.7 1191.8 1204.2 1204.8 1205.7 1000.6 1007.3 1024.0 1027.1 1030.5 6675.7 6749.3 6770.3 6870.3 6919.2 1019.2 1057.5 1063.9 1076.8 1093.4 4114.8 4128.2 4139.2 4224.2 4283.0 14393.3 14835.3 14835.7 14941.6 14945.6 14333.9 14472.0 14760.6 14814.3 14845.3 10708.7 10709.8 10757.5 10826.6 10896.6 6585.2 6620.9 6649.0 6659.4 6668.9 12789.9 12889.4 12905.2 12908.7 13243.5 13130.8 13192.3 13227.8 13326.6 13339.3 8336.5 8953.9 9203.8 9313.3 9322.3 10522.5 10525.5 10571.5 10630.9 10642.6 8920.4 8944.1 9088.0 9312.7 9316.4 8672.7 8790.2 8806.6 8827.4 9081.1 7880.0 7961.2 7984.3 8027.2 8034.7 5698.6 5711.1 5766.7 5791.0 5792.0 5712.4 5752.5 5759.3 5823.4 5848.6 09:06:04 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | [#4] prototype.html Cycles:180 Average:4841.03 Median:5376.70 stddev:1274.52 (23.7%) stddev-sans-first:1234.19 09:06:04 INFO - PROCESS | 3580 | Values: 422.2 430.5 434.4 441.8 442.5 5168.7 5264.0 5342.7 5379.9 5396.6 5213.4 5220.0 5251.0 5251.6 5274.5 4305.9 4448.2 4454.2 4456.8 4466.9 4459.0 4464.6 4517.4 4536.2 4539.6 4518.8 4524.2 4526.6 4547.2 4563.6 4422.9 4456.8 4485.9 4492.8 4507.2 4206.8 4224.3 4235.8 4245.3 4248.3 5110.0 5194.9 5195.2 5215.2 5223.8 4844.8 5065.7 5126.6 5130.2 5134.2 5449.9 5483.4 5526.0 5529.4 5540.8 4603.7 4619.4 4628.7 4667.2 4671.7 5373.5 5487.3 5587.6 5705.1 5714.8 4579.2 4642.7 4687.2 4751.5 4767.5 4828.2 4832.6 4837.8 4847.2 4849.5 4804.5 4879.0 5106.0 5115.1 5125.2 5511.8 5512.2 5512.9 5524.7 5568.9 1638.7 1660.9 1670.2 1670.9 1676.8 1235.3 1279.6 1297.4 1301.7 1319.3 5366.7 5420.0 5471.9 5495.9 5516.0 5612.9 5650.7 5673.7 5709.8 5779.3 5083.4 5422.5 5461.6 5461.9 5462.8 3004.8 3017.6 3028.7 3031.4 3031.9 5575.7 5878.6 5884.3 5889.5 5909.6 5839.0 5846.4 5855.7 5862.7 5867.3 5897.6 5902.2 5904.7 5942.5 5957.5 5433.0 5664.5 5692.6 5722.0 5728.2 5669.0 5673.4 5676.8 5682.4 5718.9 5562.9 5659.0 5721.8 5805.2 5840.9 4737.8 4754.3 4765.5 4774.3 4784.9 5473.6 5648.9 5707.2 5712.2 5737.7 5477.9 5490.7 5503.2 5510.0 5527.4 5449.3 5460.9 5519.7 5590.7 5602.8 5382.8 5482.2 5504.4 5520.6 5547.3 5530.1 5534.6 5548.2 5556.2 5587.5 5430.9 5433.2 5445.0 5493.4 5540.7 09:06:04 INFO - PROCESS | 3580 | 09:06:04 INFO - PROCESS | 3580 | [#5] yui.html Cycles:180 Average:18479.65 Median:44746.55 stddev:23598.39 (52.7%) stddev-sans-first:23627.29 09:06:04 INFO - PROCESS | 3580 | Values: 810.7 833.6 865.6 875.7 881.2 16760.0 17044.3 17072.3 17310.7 17328.1 16494.8 16716.4 16729.0 16744.8 16749.9 832.1 856.7 862.8 863.0 863.5 863.4 866.3 866.4 867.3 869.1 921.9 922.5 922.6 923.0 923.0 914.9 918.0 918.3 918.7 918.9 676.1 676.8 676.9 677.3 677.5 16489.3 16656.4 16805.8 18116.9 18118.8 15873.8 16233.0 16333.8 16408.5 16632.8 13347.9 13698.5 13729.4 13743.9 13827.7 4019.7 4052.5 4054.1 4058.6 4129.5 88488.6 88815.6 89580.0 89822.6 90170.0 11545.1 11739.1 11751.4 11773.3 12087.0 23993.8 24236.5 24355.5 24543.6 24572.3 8501.5 8604.7 8639.4 8641.0 8859.4 4887.9 4983.7 4984.3 5107.4 5185.7 3695.0 3744.7 3755.0 3762.3 3803.2 596.4 596.7 603.6 610.3 616.8 792.4 804.2 814.9 835.2 835.6 10906.4 11279.3 11283.2 12160.1 12186.9 648.6 660.9 669.0 670.6 671.0 5015.4 5354.8 5357.3 5418.9 5428.3 79373.0 79440.6 82243.6 82358.0 85018.6 70212.6 70922.6 71413.0 72148.0 73097.3 62727.7 64011.7 65021.7 66338.7 67530.0 9962.8 10191.4 10211.9 10271.1 10322.8 44299.3 44596.3 44896.8 45047.8 45318.3 50085.2 50155.7 50794.2 50802.0 52752.2 12349.6 12581.8 12811.4 12864.9 12950.4 13685.1 13868.0 13998.2 14021.9 14137.7 12530.9 12609.7 12720.0 12788.6 12807.2 13540.7 13597.8 13615.5 13866.6 13896.0 13685.2 13950.9 14008.4 14056.3 14113.6 10265.3 10301.2 10555.9 10758.6 10865.6 11179.1 11288.3 11400.7 11431.9 11476.9 09:06:04 INFO - PROCESS | 3580 | -------- Summary: end -------- 09:06:04 INFO - PROCESS | 3580 | 09:06:05 INFO - TEST-INFO | 3580: exit 0 09:06:05 INFO - TEST-OK | dromaeo_css | took 1118745ms 09:06:05 INFO - TEST-START | kraken 09:06:05 INFO - operating with platform_type : linux_ 09:06:05 INFO - Initialising browser for kraken test... 09:06:05 INFO - TEST-INFO | started process 3899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK98rj6/profile http://localhost:47608/getInfo.html) 09:06:09 INFO - PROCESS | 3899 | __metrics Screen width/height:1600/1200 09:06:09 INFO - PROCESS | 3899 | colorDepth:24 09:06:09 INFO - PROCESS | 3899 | Browser inner width/height: 1024/697 09:06:09 INFO - PROCESS | 3899 | __metrics 09:06:10 INFO - PROCESS | 3899 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:06:10 INFO - PROCESS | 3899 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:11 INFO - TEST-INFO | 3899: exit 0 09:06:11 INFO - Browser initialized. 09:06:11 INFO - Running cycle 1/1 for kraken test... 09:06:11 INFO - TEST-INFO | started process 3993 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK98rj6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:22 INFO - PROCESS | 3993 | RSS: Main: 139403264 09:06:22 INFO - PROCESS | 3993 | 09:06:25 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47608/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:26 INFO - PROCESS | 3993 | RSS: Main: 182530048 09:06:26 INFO - PROCESS | 3993 | 09:06:28 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47608/tests/kraken/driver.html?testName=audio-dft) 09:06:29 INFO - PROCESS | 3993 | RSS: Main: 173404160 09:06:29 INFO - PROCESS | 3993 | 09:06:32 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47608/tests/kraken/driver.html?testName=audio-fft) 09:06:32 INFO - PROCESS | 3993 | RSS: Main: 265498624 09:06:32 INFO - PROCESS | 3993 | 09:06:34 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47608/tests/kraken/driver.html?testName=audio-oscillator) 09:06:35 INFO - PROCESS | 3993 | RSS: Main: 171630592 09:06:35 INFO - PROCESS | 3993 | 09:06:37 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47608/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:38 INFO - PROCESS | 3993 | RSS: Main: 174587904 09:06:38 INFO - PROCESS | 3993 | 09:06:41 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47608/tests/kraken/driver.html?testName=imaging-darkroom) 09:06:41 INFO - PROCESS | 3993 | RSS: Main: 209838080 09:06:41 INFO - PROCESS | 3993 | 09:06:45 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47608/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:45 INFO - PROCESS | 3993 | RSS: Main: 212123648 09:06:45 INFO - PROCESS | 3993 | 09:06:48 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47608/tests/kraken/driver.html?testName=json-parse-financial) 09:06:48 INFO - PROCESS | 3993 | RSS: Main: 210599936 09:06:48 INFO - PROCESS | 3993 | 09:06:50 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47608/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:51 INFO - PROCESS | 3993 | RSS: Main: 173076480 09:06:51 INFO - PROCESS | 3993 | 09:06:52 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47608/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:53 INFO - PROCESS | 3993 | RSS: Main: 179892224 09:06:53 INFO - PROCESS | 3993 | 09:06:55 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47608/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:55 INFO - PROCESS | 3993 | RSS: Main: 189018112 09:06:55 INFO - PROCESS | 3993 | 09:06:58 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47608/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:06:59 INFO - PROCESS | 3993 | RSS: Main: 197578752 09:06:59 INFO - PROCESS | 3993 | 09:07:02 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47608/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:07:02 INFO - PROCESS | 3993 | RSS: Main: 194691072 09:07:02 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | Cycle 1(1): loaded http://localhost:47608/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47608/tests/kraken/driver.html?testName=ai-astar) 09:07:04 INFO - PROCESS | 3993 | RSS: Main: 246263808 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | __start_tp_report 09:07:04 INFO - PROCESS | 3993 | _x_x_mozilla_page_load 09:07:04 INFO - PROCESS | 3993 | _x_x_mozilla_page_load_details 09:07:04 INFO - PROCESS | 3993 | |i|pagename|runs| 09:07:04 INFO - PROCESS | 3993 | |0;ai-astar;102;112;112;112;112;112;112;112;114;113 09:07:04 INFO - PROCESS | 3993 | |1;audio-beat-detection;142;115;115;147;115;116;115;114;133;120 09:07:04 INFO - PROCESS | 3993 | |2;audio-dft;161;160;161;160;160;160;161;160;160;161 09:07:04 INFO - PROCESS | 3993 | |3;audio-fft;75;76;75;127;75;76;75;75;106;75 09:07:04 INFO - PROCESS | 3993 | |4;audio-oscillator;83;82;112;82;82;82;94;83;82;99 09:07:04 INFO - PROCESS | 3993 | |5;imaging-gaussian-blur;113;113;113;112;113;113;113;113;114;114 09:07:04 INFO - PROCESS | 3993 | |6;imaging-darkroom;147;146;148;145;146;146;147;146;146;147 09:07:04 INFO - PROCESS | 3993 | |7;imaging-desaturate;94;94;93;93;94;94;94;94;94;94 09:07:04 INFO - PROCESS | 3993 | |8;json-parse-financial;55;56;55;54;54;54;57;55;62;54 09:07:04 INFO - PROCESS | 3993 | |9;json-stringify-tinderbox;54;54;53;54;54;54;53;53;54;54 09:07:04 INFO - PROCESS | 3993 | |10;stanford-crypto-aes;76;85;76;76;84;78;76;80;81;79 09:07:04 INFO - PROCESS | 3993 | |11;stanford-crypto-ccm;125;121;122;122;137;120;130;120;156;129 09:07:04 INFO - PROCESS | 3993 | |12;stanford-crypto-pbkdf2;176;160;165;168;162;163;160;166;162;170 09:07:04 INFO - PROCESS | 3993 | |13;stanford-crypto-sha256-iterative;61;64;65;64;59;60;62;60;61;59 09:07:04 INFO - PROCESS | 3993 | __end_tp_report 09:07:04 INFO - PROCESS | 3993 | __start_cc_report 09:07:04 INFO - PROCESS | 3993 | _x_x_mozilla_cycle_collect,1636 09:07:04 INFO - PROCESS | 3993 | __end_cc_report 09:07:04 INFO - PROCESS | 3993 | __startTimestamp1492358824512__endTimestamp 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | ------- Summary: start ------- 09:07:04 INFO - PROCESS | 3993 | Number of tests: 14 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:3.33 (3.0%) stddev-sans-first:0.71 09:07:04 INFO - PROCESS | 3993 | Values: 102.0 112.0 112.0 112.0 112.0 112.0 112.0 112.0 114.0 113.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:118.00 stddev:12.61 (10.7%) stddev-sans-first:11.40 09:07:04 INFO - PROCESS | 3993 | Values: 142.0 115.0 115.0 147.0 115.0 116.0 115.0 114.0 133.0 120.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.52 (0.3%) stddev-sans-first:0.50 09:07:04 INFO - PROCESS | 3993 | Values: 161.0 160.0 161.0 160.0 160.0 160.0 161.0 160.0 160.0 161.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#3] audio-fft Cycles:10 Average:83.50 Median:75.00 stddev:18.09 (24.1%) stddev-sans-first:18.92 09:07:04 INFO - PROCESS | 3993 | Values: 75.0 76.0 75.0 127.0 75.0 76.0 75.0 75.0 106.0 75.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:82.50 stddev:10.34 (12.5%) stddev-sans-first:10.81 09:07:04 INFO - PROCESS | 3993 | Values: 83.0 82.0 112.0 82.0 82.0 82.0 94.0 83.0 82.0 99.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 09:07:04 INFO - PROCESS | 3993 | Values: 113.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 114.0 114.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 09:07:04 INFO - PROCESS | 3993 | Values: 147.0 146.0 148.0 145.0 146.0 146.0 147.0 146.0 146.0 147.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:07:04 INFO - PROCESS | 3993 | Values: 94.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.00 stddev:2.46 (4.5%) stddev-sans-first:2.60 09:07:04 INFO - PROCESS | 3993 | Values: 55.0 56.0 55.0 54.0 54.0 54.0 57.0 55.0 62.0 54.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#9] json-stringify-tinderbox Cycles:10 Average:53.70 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 09:07:04 INFO - PROCESS | 3993 | Values: 54.0 54.0 53.0 54.0 54.0 54.0 53.0 53.0 54.0 54.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.50 stddev:3.38 (4.3%) stddev-sans-first:3.40 09:07:04 INFO - PROCESS | 3993 | Values: 76.0 85.0 76.0 76.0 84.0 78.0 76.0 80.0 81.0 79.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#11] stanford-crypto-ccm Cycles:10 Average:128.20 Median:127.00 stddev:11.19 (8.8%) stddev-sans-first:11.81 09:07:04 INFO - PROCESS | 3993 | Values: 125.0 121.0 122.0 122.0 137.0 120.0 130.0 120.0 156.0 129.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.50 stddev:5.03 (3.0%) stddev-sans-first:3.50 09:07:04 INFO - PROCESS | 3993 | Values: 176.0 160.0 165.0 168.0 162.0 163.0 160.0 166.0 162.0 170.0 09:07:04 INFO - PROCESS | 3993 | 09:07:04 INFO - PROCESS | 3993 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:2.17 (3.5%) stddev-sans-first:2.30 09:07:04 INFO - PROCESS | 3993 | Values: 61.0 64.0 65.0 64.0 59.0 60.0 62.0 60.0 61.0 59.0 09:07:04 INFO - PROCESS | 3993 | -------- Summary: end -------- 09:07:04 INFO - PROCESS | 3993 | 09:07:05 INFO - TEST-INFO | 3993: exit 0 09:07:05 INFO - TEST-OK | kraken | took 60235ms 09:07:05 INFO - SUITE-END | took 1178s 09:07:05 INFO - Completed test suite (00:19:39) 09:07:05 INFO - javascript benchmark 09:07:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7801.77758407507, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3391.5421418108554, 3413.1808365992156, 3415.4193787056183, 3433.5364725762015, 3458.273762509873, 5755.7697692092315, 5789.942100578993, 5802.883942321151, 5829.329627092886, 5831.067029275317, 5904.350521442638, 5968.641881487114, 5970.014947533663, 5972.283326000875, 5977.731002104905, 5336.066188417021, 5365.03429382711, 5405.4324295948945, 5406.351237851443, 5495.093309603911, 5234.8691282717855, 5283.947160528384, 5465.043617366965, 5471.972640136813, 5473.863153421176, 5292.285541451913, 5339.9733001335135, 5353.973230133844, 5364.570834333244, 5400.270963419947, 5361.678299302054, 5385.78456861725, 5389.892202155974, 5397.27136836526, 5406.02691515529, 5335.1197052486295, 5398.109311963522, 5404.0272750905215, 5413.350397952231, 5416.187571864212, 5961.940380596182, 5991.6405015698765, 5992.161097446361, 5998.040313549811, 5998.1002849572915, 6123.953766440822, 6130.049842274455, 6375.2987171411105, 6376.776812811529, 6386.552941294111, 6248.625082495065, 6326.65203413812, 6408.51936104794, 6418.454431373339, 6437.066625339299, 5949.4751046813935, 5954.523638108941, 5955.404459554054, 5963.016102343152, 5963.701814909237, 6459.09572659828, 6490.578112422678, 6493.642849643315, 6495.707693153725, 6504.05691174777, 5943.55423343251, 5975.312839023568, 6102.115193296947, 6121.265448146251, 6145.217886812774, 6247.187865577446, 6297.2128483939505, 6299.5275354348605, 6302.0, 6304.779332723333, 6227.439530442282, 6242.781502647385, 6281.466075383552, 6288.968555157195, 6291.339409361951, 6048.909266361008, 6112.083187521907, 6198.411150940653, 6235.532335074888, 6332.208473940757, 4687.601553867891, 4823.517648235148, 4824.8793780155775, 4831.9275210871865, 4854.466008739036, 4191.350340697197, 4196.496420429568, 4210.7473551587, 4227.070044590185, 4229.238737027303, 6013.548983826143, 6014.849628759316, 6064.605800622945, 6070.3929607038945, 6070.969645151746, 6110.111264678734, 6154.1999540059505, 6242.687865606701, 6367.108604795332, 6375.043743438521, 6085.939140608537, 6086.573939824259, 6088.6042407243385, 6091.177691011745, 6131.386861313833, 5367.980083784147, 5375.838724838261, 5376.247325374451, 5378.731063446812, 5382.703951282717, 6342.39747224013, 6394.872102558016, 6567.146270984741, 6570.244421891448, 6581.078648989145, 6244.656543889948, 6247.687615619292, 6347.39699728525, 6387.968060159669, 6388.648624325707, 6538.8365290868105, 6546.854300497299, 6549.7380104795275, 6555.80332590023, 6559.147310849559, 6151.6924153793025, 6271.0, 6272.686365681789, 6273.560850740404, 6277.246730392382, 6130.295016073209, 6141.087130295657, 6262.279837818709, 6313.873722525432, 6398.168238129014, 6247.562670613013, 6248.718807653576, 6251.749930002749, 6256.343083976299, 6256.374362563707, 6187.690615469299, 6202.906956395748, 6231.096491008688, 6234.875302494015, 6242.906356404748, 5983.162357270073, 6053.122297266959, 6099.542534309856, 6114.052321890114, 6234.127222188807, 6234.9688251558455, 6255.999040153553, 6274.121622972696, 6280.905786413299, 6281.905771413341, 5882.205902203148, 5911.817636472809, 6075.513958883367, 6117.694115294306, 6127.662978536223], "value": 5817.403977205272, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.5089192025183, 797.1828875402713, 816.5631387207843, 884.929205663547, 896.4486840593037, 30339.241518962037, 43182.0, 44858.0, 44860.55139448604, 44886.10227795442, 44622.0, 44779.328310075296, 44818.0, 44971.55028449715, 44977.325340119925, 7539.509931854439, 7699.076110866704, 9047.45715257085, 9132.817343653123, 9183.908160918405, 10036.096751292382, 10277.640282590111, 10285.33145345554, 10286.588536458532, 10633.043026127647, 10332.896671033268, 10401.323913945569, 10412.739681507946, 10414.375137491737, 10421.114205292559, 10326.328788628753, 10341.172706183488, 10354.06813386795, 10357.741056473573, 10359.32664376813, 10415.479226038668, 10460.843087353696, 10526.473676316153, 10529.210309226839, 10556.736081597945, 41716.0, 41738.58261417377, 41837.37243941343, 41886.162276754294, 41892.0, 4106.7946602670045, 4122.855700050483, 4161.52142503613, 4225.471816022997, 4249.2776228041, 29250.707492925223, 29284.8535757322, 29310.85344573285, 29369.853150734325, 29839.25401864949, 4191.91616167678, 4263.317869140954, 4305.483341998949, 4322.675799315032, 4376.409184760048, 229543.0, 229886.0, 231054.0, 231315.8434207835, 231549.0, 53051.0, 53103.468965310625, 53232.467675323525, 53293.46706532963, 53320.0, 55093.0, 55257.447425526036, 55261.0, 55539.72230138904, 55554.722226389415, 15709.45016924402, 15964.36142554308, 15985.60035999086, 15985.840141598668, 16000.0, 11220.887791122148, 11361.602343917923, 13821.032527723164, 13909.721805563831, 13918.58244252674, 13272.27002514871, 13314.134581252187, 13412.725791049834, 13415.731685366238, 13500.527481538296, 5236.905237904793, 5250.7374631268285, 5277.419483856773, 5322.467753224724, 5390.595705322038, 1006.6426418621284, 1007.773251018515, 1008.7528555503949, 1018.3177271228295, 1027.4503140819775, 27628.723712763018, 27753.444931101265, 28073.298167545567, 28187.0, 28308.575371369447, 1066.5253961986996, 1070.9785804284027, 1074.5379486820586, 1084.728817795551, 1102.2339474065448, 14557.344919478648, 14903.0, 14918.0, 15028.849711502964, 15068.773968390484, 296024.0, 299351.0, 300549.0, 300683.0, 301592.0, 54839.177412338846, 54998.90002200014, 55178.44821551813, 55245.723771380886, 55351.0, 1471.0879254862054, 1503.1131632336974, 1520.931558079889, 1543.8456154384696, 1545.1424459424902, 25312.24063278031, 26525.204243872715, 26837.463250734487, 26930.59604105901, 27133.050343237894, 41068.0, 41107.383389248585, 41259.38110928278, 41319.7934010328, 41383.586164137974, 255876.0, 256496.0, 256711.0, 257946.0, 258316.0, 29268.853655731586, 29341.559876602296, 35088.0, 35223.0, 35229.0, 41699.58300416957, 42626.36060459157, 42678.78660606677, 42719.35920961126, 43652.56347436485, 32984.505232421056, 33240.1689957753, 33352.66647333495, 33622.83188584042, 34190.82904585461, 32792.67207327896, 33000.504992424656, 33180.34098295027, 33329.0, 33402.16494587558, 37284.0, 37312.813435933735, 37337.626623734504, 37640.43539346857, 38384.42423363707, 31719.207019823767, 31863.522047168848, 31865.681343186272, 31916.84041579777, 32177.195570110933, 24392.51214975655, 25218.24345269645, 26887.596686050118, 27050.188494345202, 27318.453630927666], "value": 18621.217210901148, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.305612682946, 467.61889060415757, 469.70173939548374, 484.6098890393234, 484.65105124310486, 18768.90615546922, 18980.62038759224, 20477.38567842965, 20518.89740551295, 20658.0, 20808.687869681966, 21081.789182108176, 21212.469688257803, 21423.250186243484, 21863.0, 6573.572717773353, 6808.0, 6859.348361905636, 6862.073620061277, 7339.119305683325, 7609.124950630665, 7694.923050769505, 7705.6917723291, 7712.691492340327, 7714.421418393643, 7570.659320330598, 7675.69297228113, 7811.843763124734, 7814.609269536529, 7820.569868657221, 7567.356774674159, 7729.149793522708, 7741.187175346592, 7775.883361749578, 7780.805479862992, 7263.382612477946, 7476.514026588255, 7480.513766605153, 7489.775306740807, 7492.213317601628, 25658.230253092435, 25780.8710956444, 25915.611265831027, 26224.475510489876, 26345.3413664658, 3184.4528467532095, 3202.2794871154038, 3582.1223800168864, 3639.6724294813594, 3734.458503517002, 15157.696846063127, 15756.606084847856, 15824.920875395665, 15969.0, 16076.115813630246, 2229.843910926236, 2284.7144106986625, 2315.351701523576, 2354.399628094842, 2357.988210058939, 104534.47732761364, 106134.46932765287, 106253.0, 106339.0, 108037.45981270043, 14124.312806922288, 15972.20138993069, 18028.54928626795, 18068.728969065476, 18136.63726725458, 31501.8424907874, 31528.527072093937, 31532.684673152973, 31578.210544736106, 32322.0, 10069.244806639532, 10119.392836429837, 10152.644657437102, 10211.336263142872, 10308.12380947628, 8467.576621168917, 8496.617652205665, 8507.447015943944, 8670.91329086701, 8698.652053917898, 9121.133492318218, 9140.360174787835, 9181.540922953825, 9324.766880827898, 9371.906280937103, 3682.963170368262, 3725.850965961331, 3801.4867992820614, 3814.389697648363, 3883.3980732986433, 951.3388195204267, 1088.4068182840372, 1186.649938268213, 1187.4300335838846, 1208.3535308610028, 20136.494898927744, 20388.79611203899, 20442.591148176954, 20486.282980095624, 20758.273460429114, 1161.7095726068483, 1174.7444905780922, 1186.9762604747857, 1190.4285942747356, 1215.6960759810047, 10762.300450470695, 10966.506507207196, 11128.332300062022, 11172.385518796544, 11263.155263355282, 601318.0, 605737.0, 647270.0, 680351.0, 695625.0, 117411.41294293644, 118640.40679796546, 119824.0, 120777.0, 123432.38283808703, 1252.3863306979515, 1259.2381609126346, 1295.4429595273934, 1312.4881296294452, 1336.4253371050347, 14655.0, 19201.423957281302, 19298.7105193425, 19299.903500482407, 19378.321758738377, 51602.74198628983, 51750.741246293524, 51797.741011294704, 52017.21974170466, 52183.73908130435, 62119.37880621136, 62547.0, 63080.0, 63633.36366636274, 65363.67318163569, 11431.371274579978, 11671.416429178338, 11672.474738636613, 11816.940915295369, 11985.820212696643, 12965.0, 13642.386092626055, 13899.444022238997, 14100.153990760587, 14144.646383840487, 11137.498812553293, 11364.659060228207, 11460.541578336773, 11880.34658093813, 11946.880531194576, 11541.769164616828, 11574.131940104357, 11620.0, 11780.882191177978, 11804.0, 12050.75898482043, 12256.38718064111, 12305.077119215915, 12448.564300249447, 12573.811392828931, 12144.453499592362, 12208.75582488363, 12354.75290494203, 12359.443825028075, 12391.938040310102, 11600.767984640428, 12324.198927069712, 12441.93779031099, 12569.805868442483, 12683.11218214716], "value": 11576.713438886163, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1547.9690406191876, 1561.3598424645897, 1565.5459916624177, 1577.2902194012693, 1593.1715507935867, 10169.084782369586, 10849.240553161291, 10850.24048316618, 10853.51159197836, 10857.837132442999, 10392.324498907567, 10531.63139290125, 10602.734931626714, 10638.095761860232, 10642.467876606177, 2394.3774618599114, 2408.3738228060743, 2431.987840060797, 2434.8295619306655, 2435.1720415058876, 2336.906523739048, 2390.3546042568496, 2410.0359856057544, 2410.963835542468, 2412.9155479558217, 2362.3503536527505, 2427.1141033522813, 2430.7812296893276, 2434.4522482441484, 2439.5730747119183, 2252.6395776675654, 2329.7204335479764, 2333.5099629077913, 2334.7898689117974, 2339.2982105368405, 2221.900159421081, 2226.109556177526, 2232.3191426614876, 2232.79904808568, 2232.810211132056, 10771.83842242367, 10884.292520986111, 10894.836577451344, 10908.69095708512, 10918.0, 5635.070213414781, 5789.855253618651, 5793.131030345482, 5795.73919173634, 5801.100829371454, 3454.2055327274866, 3549.4853246279404, 3574.6067932527403, 3578.9284214315567, 3647.9817600911824, 1232.7472868062116, 1245.1657389549023, 1254.4417734108326, 1283.4481173095564, 1327.071050264809, 15182.0, 15544.222788860512, 16040.598985025352, 16221.432249871315, 16325.755113673304, 4549.772511374485, 4606.424196975378, 4756.9286460703115, 4757.167495688241, 4758.714477131383, 10889.292196007234, 11108.666739997812, 12405.44175512104, 12409.937950310372, 12434.129610927148, 5673.971630141906, 5851.6781577012825, 5945.732442040118, 5958.76164953397, 5964.194833697482, 5388.676679399248, 5412.729363531807, 5530.889382212334, 5535.308086489189, 5537.612367134262, 4285.142971405731, 4290.935635965463, 4373.343998400202, 4406.0, 4413.757243351646, 1156.6876943225282, 1191.839101721274, 1204.235310577772, 1204.8253003314471, 1205.7166565857017, 1000.5797565022708, 1007.2697294461457, 1023.9641612543526, 1027.1166796554958, 1030.4899074957943, 6675.699593518303, 6749.257581666013, 6770.255271920085, 6870.27862074485, 6919.169699635975, 1019.2378653848007, 1057.5135437698746, 1063.9095676867398, 1076.7738774857369, 1093.3822390349585, 4114.773687447159, 4128.2156390285745, 4139.234241665302, 4224.218519573888, 4282.957170428318, 14393.28033598316, 14835.332410041574, 14835.703285934436, 14941.551753447415, 14945.551633450796, 14333.928330358282, 14472.0, 14760.63098422548, 14814.259287035606, 14845.331960061607, 10708.73228169302, 10709.78580428387, 10757.462126893624, 10826.566937322576, 10896.564137434572, 6585.209774827052, 6620.933790662032, 6649.0, 6659.367360100783, 6668.933310667026, 12789.872101278868, 12889.355532223539, 12905.161164524276, 12908.741825163632, 13243.536476223286, 13130.803037954629, 13192.340382980621, 13227.80158297607, 13326.600201993955, 13339.266340351374, 8336.45813022176, 8953.910460895308, 9203.76990575241, 9313.34806563534, 9322.254219662274, 10522.526486307981, 10525.526351314362, 10571.471426428801, 10630.893691062991, 10642.574297028032, 8920.375573709778, 8944.105589441004, 9087.954560227421, 9312.674056407994, 9316.44101353921, 8672.653093876173, 8790.164934331227, 8806.60370283326, 8827.382083254111, 9081.091890810865, 7880.0, 7961.16407777175, 7984.281414672475, 8027.157148499324, 8034.718784842503, 5698.601097923106, 5711.057675483498, 5766.711664416846, 5791.0, 5792.0443126885275, 5712.37163911961, 5752.511036561772, 5759.2512973313205, 5823.35943046273, 5848.619839710405], "value": 5258.770980129497, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.1915032712356, 430.50706940553056, 434.41788004074533, 441.8166460918717, 442.46903715541356, 5168.664036837612, 5263.9736801315985, 5342.652727572705, 5379.919301210485, 5396.568274538037, 5213.400458947222, 5220.0, 5250.976059668366, 5251.632385733, 5274.525292723654, 4305.892352691192, 4448.221561226788, 4454.175977444183, 4456.79944402501, 4466.8883277917985, 4459.0, 4464.6428285737065, 4517.41273634427, 4536.206163921317, 4539.6141327987, 4518.751468669222, 4524.163029839474, 4526.592606665399, 4547.18150732868, 4563.5664611861985, 4422.867313980586, 4456.754878481682, 4485.865424037284, 4492.775361231942, 4507.233770259064, 4206.810693518768, 4224.331389101288, 4235.809388577491, 4245.27830268855, 4248.277792775236, 5110.0, 5194.870128246786, 5195.181686409765, 5215.217717342429, 5223.817166399158, 4844.781984810692, 5065.746712664352, 5126.5642420394315, 5130.204818253177, 5134.1773670346865, 5449.89356067042, 5483.396826349099, 5526.0, 5529.419410961871, 5540.833774986717, 4603.700759450625, 4619.445666520006, 4628.722276663411, 4667.206574882278, 4671.743054132022, 5373.489518495737, 5487.341519017704, 5587.580931430159, 5705.0635061807225, 5714.79998200061, 4579.175748365326, 4642.675012749142, 4687.20317546018, 4751.548602882721, 4767.4994125617, 4828.179209534387, 4832.565069143794, 4837.830675926396, 4847.175980083394, 4849.4908034656555, 4804.543568361, 4879.0, 5105.974470127626, 5115.0792857285305, 5125.231215317657, 5511.779528818803, 5512.173174023928, 5512.889742205213, 5524.66851988882, 5568.914061757919, 1638.7463590353389, 1660.9335626574803, 1670.223346144049, 1670.874684398675, 1676.8239334869663, 1235.2773627427875, 1279.5969269680022, 1297.351324337831, 1301.6615679923289, 1319.2678063674562, 5366.731663416814, 5420.0, 5471.890562188734, 5495.862603434946, 5516.0, 5612.85967850807, 5650.7174641267775, 5673.74468148926, 5709.773977702668, 5779.306483221956, 5083.415407228142, 5422.539084177889, 5461.590380721462, 5461.853010867762, 5462.808801691922, 3004.8046876952926, 3017.562453444238, 3028.727414532703, 3031.4240294344004, 3031.863566139529, 5575.721213939287, 5878.588498805043, 5884.264466941632, 5889.527618095476, 5909.5863289569315, 5839.043920286752, 5846.386129456346, 5855.677937713466, 5862.677552734641, 5867.29848343984, 5897.616654917502, 5902.232709747877, 5904.734286957183, 5942.4549617099865, 5957.527626035234, 5432.972835135799, 5664.479306708475, 5692.5730570208225, 5722.0, 5728.226689396878, 5669.007923613269, 5673.404292549223, 5676.801311954062, 5682.403347648437, 5718.857028574319, 5562.916556251578, 5659.0193432298365, 5721.828345149652, 5805.216295800013, 5840.883182336244, 4737.810487580458, 4754.263089221176, 4765.475797662324, 4774.3315935769715, 4784.856454306377, 5473.564263902525, 5648.887022259614, 5707.229524014371, 5712.171735098471, 5737.713114344349, 5477.863053423696, 5490.670559766427, 5503.229547863222, 5510.043934403229, 5527.44725527444, 5449.264349312792, 5460.890782184255, 5519.724013799214, 5590.692511911721, 5602.775888964396, 5382.838514844561, 5482.23248745184, 5504.394516603091, 5520.641158324696, 5547.306586676666, 5530.142827861688, 5534.640248384003, 5548.167774833646, 5556.249906262603, 5587.497125258747, 5430.864228394322, 5433.157860531624, 5444.972775136099, 5493.423190564933, 5540.697935985121], "value": 4473.6165113081715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.7162493127403, 833.6082041440528, 865.5931712095315, 875.7066382761775, 881.1628952495128, 16760.0, 17044.31822727091, 17072.317107315692, 17310.653786924253, 17328.133593320348, 16494.75257871133, 16716.41492547761, 16729.0, 16744.83255167448, 16749.91625041873, 832.097174565597, 856.7044369692446, 862.8188080503102, 862.9956850215772, 863.5078005536847, 863.4301361101674, 866.263675875504, 866.4368160695535, 867.2714919467646, 869.1091631078132, 921.889373275208, 922.4972390047441, 922.6263363337825, 923.0123767568704, 923.0492592629603, 914.862770584411, 917.9954100229523, 918.274563095154, 918.7243826851918, 918.9264858811279, 676.0805304785488, 676.7834293026234, 676.8781619308522, 677.3362105136994, 677.4614181725491, 16489.257983390777, 16656.41702540405, 16805.83194168055, 18116.90941545297, 18118.818811811845, 15873.841261587351, 16233.0, 16333.83666163335, 16408.507744767558, 16632.750508742378, 13347.933260333637, 13698.452061917511, 13729.382177802023, 13743.862561374459, 13827.654308642264, 4019.738716983416, 4052.4731784867777, 4054.148628787958, 4058.5738497457933, 4129.504459464883, 88488.55755721245, 88815.55592221998, 89580.0, 89822.55088724515, 90170.0, 11545.134114941413, 11739.119566032581, 11751.412429378497, 11773.293602383885, 12087.033037357167, 23993.760062399153, 24236.515269694508, 24355.512889742105, 24543.63184552233, 24572.262832115066, 8501.48991060526, 8604.698835540852, 8639.395242332976, 8641.0, 8859.3798434109, 4887.877803054881, 4983.700977941335, 4984.277279794482, 5107.361579802525, 5185.662931909414, 3695.0, 3744.719146064056, 3755.004923695223, 3762.266358060152, 3803.1633040730994, 596.3589141672633, 596.686739461786, 603.6227357901312, 610.3499772742016, 616.7563812294119, 792.397777688949, 804.1797366686064, 814.8510600053895, 835.1773503099442, 835.6197929941862, 10906.400147991937, 11279.26684765504, 11283.210175287815, 12160.14878958482, 12186.939065304794, 648.6367634124905, 660.9438197753149, 668.9899651505232, 670.5875886329871, 670.963097029668, 5015.448300686921, 5354.759035843396, 5357.330333708286, 5418.864528386822, 5428.294321738149, 79373.0, 79440.6027969868, 82243.58878205571, 82358.0, 85018.57490712508, 70212.64893675601, 70922.64538677377, 71413.0, 72148.0, 73097.26902730904, 62727.6863615679, 64011.67994160092, 65021.674891626186, 66338.66830665816, 67529.9870501943, 9962.800743984935, 10191.439470829175, 10211.876693563554, 10271.126954208976, 10322.845157322496, 44299.33550996802, 44596.33105503355, 44896.77551612221, 45047.77476112729, 45318.32022519599, 50085.24872126994, 50155.74922125366, 50794.23808642799, 50802.0, 52752.20871686851, 12349.629511114681, 12581.811272830731, 12811.359432028175, 12864.935675321563, 12950.352482376033, 13685.11046781956, 13868.0, 13998.160110393408, 14021.929890350482, 14137.717245654823, 12530.886338274871, 12609.684757881127, 12720.0, 12788.552400665933, 12807.231566106064, 13540.661483462993, 13597.79603305971, 13615.52345667897, 13866.584002479942, 13896.0, 13685.247311397969, 13950.930245348709, 14008.369623366769, 14056.297185140907, 14113.576592702235, 10265.281430299807, 10301.227407944285, 10555.943010859697, 10758.626189325343, 10865.565377385132, 11179.10567154642, 11288.2662626929, 11400.714982125513, 11431.942840285745, 11476.942615286871], "value": 7642.8939826586175, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1463.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 114.0, 113.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 115.0, 147.0, 115.0, 116.0, 115.0, 114.0, 133.0, 120.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 161.0, 160.0, 160.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 127.0, 75.0, 76.0, 75.0, 75.0, 106.0, 75.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 112.0, 82.0, 82.0, 82.0, 94.0, 83.0, 82.0, 99.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 148.0, 145.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 54.0, 54.0, 54.0, 57.0, 55.0, 62.0, 54.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 54.0, 54.0, 54.0, 53.0, 53.0, 54.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 85.0, 76.0, 76.0, 84.0, 78.0, 76.0, 80.0, 81.0, 79.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 121.0, 122.0, 122.0, 137.0, 120.0, 130.0, 120.0, 156.0, 129.0], "value": 128.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 160.0, 165.0, 168.0, 162.0, 163.0, 160.0, 166.0, 162.0, 170.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 64.0, 59.0, 60.0, 62.0, 60.0, 61.0, 59.0], "value": 61.5, "unit": "score"}]}]} 09:07:05 INFO - Return code: 0 09:07:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:07:05 ERROR - # TBPL SUCCESS # 09:07:05 INFO - Running post-action listener: _resource_record_post_action 09:07:05 INFO - [mozharness: 2017-04-16 16:07:05.825295Z] Finished run-tests step (success) 09:07:05 INFO - Running post-run listener: _resource_record_post_run 09:07:05 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 7569408; Write bytes: 366161920; Read time: 1864; Write time: 276560 09:07:05 INFO - TinderboxPrint: CPU usage
13.1% 09:07:05 INFO - TinderboxPrint: I/O read bytes / time
7,569,408 / 1,864 09:07:05 INFO - TinderboxPrint: I/O write bytes / time
366,161,920 / 276,560 09:07:05 INFO - TinderboxPrint: CPU idle
8,290.9 (86.9%) 09:07:05 INFO - TinderboxPrint: CPU user
1,200.9 (12.6%) 09:07:05 INFO - TinderboxPrint: Swap in / out
0 / 0 09:07:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 110145536; Read time: 88; Write time: 91656 09:07:05 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 7565312; Write bytes: 256016384; Read time: 1776; Write time: 184904 09:07:06 INFO - Running post-run listener: _upload_blobber_files 09:07:06 INFO - Blob upload gear active. 09:07:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:07:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:07:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:07:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:07:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:07:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:07:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:07 INFO - (blobuploader) - INFO - Done attempting. 09:07:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:07:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:07:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:09 INFO - (blobuploader) - INFO - Done attempting. 09:07:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:07:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:07:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:10 INFO - (blobuploader) - INFO - Done attempting. 09:07:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:07:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:07:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:13 INFO - (blobuploader) - INFO - Done attempting. 09:07:13 INFO - (blobuploader) - INFO - Iteration through files over. 09:07:13 INFO - Return code: 0 09:07:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:07:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:07:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bb9b5b7cf5a0abd8cdaec711d133f9ca51b82a521bfa3e0aa253c5e5cf156ac897bc9f7d4bca678d825280f133727be5e55976e8447a4bfe1e300572a5e3fe54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/61bd841d2e0313fe96ed43ac62a13d59d12ff3df2e00517ef586c05d29e006c152cfa2ea0bd89179c2ca135679acb398108ce4d066c27d8fa16cca7e04c5a870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0bff062785c0587a826031abc59e9f9e14316cf5bb46b6bdc516e5ee20544cb498925f49eb0372e499064757e600521acbc0b5d0a3670b5ab76b585b72b8c434", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/67348591aeacafcbe79f74de2d293a261edb4d0febdce3160cf9f4f6b14125e83711e971aa34f6d873fb68bb63f8bee73d7de63aaab48fe6f962912a5cd358b2"} 09:07:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:07:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:07:13 INFO - Contents: 09:07:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bb9b5b7cf5a0abd8cdaec711d133f9ca51b82a521bfa3e0aa253c5e5cf156ac897bc9f7d4bca678d825280f133727be5e55976e8447a4bfe1e300572a5e3fe54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/61bd841d2e0313fe96ed43ac62a13d59d12ff3df2e00517ef586c05d29e006c152cfa2ea0bd89179c2ca135679acb398108ce4d066c27d8fa16cca7e04c5a870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0bff062785c0587a826031abc59e9f9e14316cf5bb46b6bdc516e5ee20544cb498925f49eb0372e499064757e600521acbc0b5d0a3670b5ab76b585b72b8c434", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/67348591aeacafcbe79f74de2d293a261edb4d0febdce3160cf9f4f6b14125e83711e971aa34f6d873fb68bb63f8bee73d7de63aaab48fe6f962912a5cd358b2"} 09:07:13 INFO - Running post-run listener: copy_logs_to_upload_dir 09:07:13 INFO - Copying logs to upload dir... 09:07:13 INFO - mkdir: /builds/slave/test/build/upload/logs 09:07:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.449868 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-04-16 09:07:13.460310) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-16 09:07:13.463907) ========= 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=781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bb9b5b7cf5a0abd8cdaec711d133f9ca51b82a521bfa3e0aa253c5e5cf156ac897bc9f7d4bca678d825280f133727be5e55976e8447a4bfe1e300572a5e3fe54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/61bd841d2e0313fe96ed43ac62a13d59d12ff3df2e00517ef586c05d29e006c152cfa2ea0bd89179c2ca135679acb398108ce4d066c27d8fa16cca7e04c5a870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0bff062785c0587a826031abc59e9f9e14316cf5bb46b6bdc516e5ee20544cb498925f49eb0372e499064757e600521acbc0b5d0a3670b5ab76b585b72b8c434", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/67348591aeacafcbe79f74de2d293a261edb4d0febdce3160cf9f4f6b14125e83711e971aa34f6d873fb68bb63f8bee73d7de63aaab48fe6f962912a5cd358b2"} build_url:https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011136 build_url: 'https://queue.taskcluster.net/v1/task/Fp5mO7BgSNeLwcqYWpziWQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bb9b5b7cf5a0abd8cdaec711d133f9ca51b82a521bfa3e0aa253c5e5cf156ac897bc9f7d4bca678d825280f133727be5e55976e8447a4bfe1e300572a5e3fe54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/61bd841d2e0313fe96ed43ac62a13d59d12ff3df2e00517ef586c05d29e006c152cfa2ea0bd89179c2ca135679acb398108ce4d066c27d8fa16cca7e04c5a870", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/0bff062785c0587a826031abc59e9f9e14316cf5bb46b6bdc516e5ee20544cb498925f49eb0372e499064757e600521acbc0b5d0a3670b5ab76b585b72b8c434", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/67348591aeacafcbe79f74de2d293a261edb4d0febdce3160cf9f4f6b14125e83711e971aa34f6d873fb68bb63f8bee73d7de63aaab48fe6f962912a5cd358b2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-16 09:07:13.489655) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 09:07:13.490015) ========= 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=781b77c4c33858b1f599d705000001c1-1492342224.179205-1508256748 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004387 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 09:07:13.575664) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-16 09:07:13.576020) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-16 09:07:13.576345) ========= ========= Total master_lag: 0.15 =========