builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-036 starttime: 1494566410.11 results: success (0) revision: 8a7d0b15595f9916123848ca906f29c62d4914c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:10.110966) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:10.111454) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:10.111728) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:10.126099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:10.126374) ========= 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=14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-11 22:20: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% 42.7M=0s 2017-05-11 22:20:10 (42.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081807 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:10.223979) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:10.224490) ========= 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=14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014706 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:10.275236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 22:20:10.275582) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8a7d0b15595f9916123848ca906f29c62d4914c9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 8a7d0b15595f9916123848ca906f29c62d4914c9 --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=14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-11 22:20:10,676 truncating revision to first 12 chars 2017-05-11 22:20:10,676 Setting DEBUG logging. 2017-05-11 22:20:10,676 attempt 1/10 2017-05-11 22:20:10,676 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8a7d0b15595f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 22:20:11,481 unpacking tar archive at: mozilla-central-8a7d0b15595f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.104049 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 22:20:11.393494) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:11.393833) ========= script_repo_revision: 8a7d0b15595f9916123848ca906f29c62d4914c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:11.394242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:11.394557) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 22:20:11.404355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-05-11 22:20:11.404647) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:20:11 INFO - MultiFileLogger online at 20170511 22:20:11 in /builds/slave/test 22:20:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 22:20:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:20:11 INFO - {'append_to_log': False, 22:20:11 INFO - 'base_work_dir': '/builds/slave/test', 22:20:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 22:20:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:20:11 INFO - 'branch': 'Firefox-Non-PGO', 22:20:11 INFO - 'buildbot_json_path': 'buildprops.json', 22:20:11 INFO - 'config_files': ('talos/linux_config.py',), 22:20:11 INFO - 'default_actions': ('clobber', 22:20:11 INFO - 'read-buildbot-config', 22:20:11 INFO - 'download-and-extract', 22:20:11 INFO - 'populate-webroot', 22:20:11 INFO - 'create-virtualenv', 22:20:11 INFO - 'install', 22:20:11 INFO - 'run-tests'), 22:20:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:20:11 INFO - 'download_minidump_stackwalk': True, 22:20:11 INFO - 'download_symbols': 'ondemand', 22:20:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:20:11 INFO - 'tooltool.py': '/tools/tooltool.py', 22:20:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:20:11 INFO - '/tools/misc-python/virtualenv.py')}, 22:20:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:20:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:20:11 INFO - 'gecko_profile': False, 22:20:11 INFO - 'gecko_profile_interval': 0, 22:20:11 INFO - 'installer_path': 'installer.exe', 22:20:11 INFO - 'log_level': 'info', 22:20:11 INFO - 'log_name': 'talos', 22:20:11 INFO - 'log_to_console': True, 22:20:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:20:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:20:11 INFO - 'opt_config_files': (), 22:20:11 INFO - 'pip_index': False, 22:20:11 INFO - 'suite': 'dromaeojs', 22:20:11 INFO - 'system_bits': '32', 22:20:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:20:11 INFO - 'title': 'talos-linux64-ix-036', 22:20:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:20:11 INFO - 'use_talos_json': True, 22:20:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:20:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:20:11 INFO - 'work_dir': 'build'} 22:20:11 INFO - [mozharness: 2017-05-12 05:20:11.908682Z] Running clobber step. 22:20:11 INFO - Running pre-action listener: _resource_record_pre_action 22:20:11 INFO - Running main action method: clobber 22:20:11 INFO - rmtree: /builds/slave/test/build 22:20:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:20:12 INFO - Running post-action listener: _resource_record_post_action 22:20:12 INFO - [mozharness: 2017-05-12 05:20:12.956759Z] Finished clobber step (success) 22:20:12 INFO - [mozharness: 2017-05-12 05:20:12.956860Z] Running read-buildbot-config step. 22:20:12 INFO - Running pre-action listener: _resource_record_pre_action 22:20:12 INFO - Running main action method: read_buildbot_config 22:20:12 INFO - Using buildbot properties: 22:20:12 INFO - { 22:20:12 INFO - "project": "", 22:20:12 INFO - "product": "firefox", 22:20:12 INFO - "who": "philringnalda@gmail.com", 22:20:12 INFO - "installer_path": "public/build/target.tar.bz2", 22:20:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:20:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 22:20:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 22:20:12 INFO - "stage_platform": "linux64", 22:20:12 INFO - "basedir": "/builds/slave/test", 22:20:12 INFO - "buildnumber": 1150, 22:20:12 INFO - "platform": "ubuntu64_hw", 22:20:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:20:12 INFO - "slavebuilddir": "test", 22:20:12 INFO - "taskId": "eADJUnifTaKy9pKUM22_zQ", 22:20:12 INFO - "branch": "mozilla-central", 22:20:12 INFO - "script_repo_revision": "production", 22:20:12 INFO - "revision": "8a7d0b15595f9916123848ca906f29c62d4914c9", 22:20:12 INFO - "slavename": "talos-linux64-ix-036", 22:20:12 INFO - "repo_path": "mozilla-central" 22:20:12 INFO - } 22:20:12 INFO - Finding installer, test and symbols from parent task. 22:20:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eADJUnifTaKy9pKUM22_zQ'}, attempt #1 22:20:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eADJUnifTaKy9pKUM22_zQ'}, attempt #1 22:20:13 INFO - Task dependencies: IDGks8c5QO6QxzWqVkabFQ 22:20:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ'}, attempt #1 22:20:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.tar.bz2 22:20:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/test_packages.json 22:20:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.crashreporter-symbols.zip 22:20:14 INFO - Running post-action listener: _resource_record_post_action 22:20:14 INFO - [mozharness: 2017-05-12 05:20:14.308890Z] Finished read-buildbot-config step (success) 22:20:14 INFO - [mozharness: 2017-05-12 05:20:14.309150Z] Running download-and-extract step. 22:20:14 INFO - Running pre-action listener: _resource_record_pre_action 22:20:14 INFO - Running main action method: download_and_extract 22:20:14 INFO - mkdir: /builds/slave/test/build/tests 22:20:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:14 INFO - trying https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.test_packages.json 22:20:14 INFO - Downloading https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 22:20:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 22:20:14 INFO - Downloaded 986 bytes. 22:20:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 22:20:14 INFO - Using the following test package requirements: 22:20:14 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 22:20:14 INFO - u'common': [u'target.common.tests.zip'], 22:20:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 22:20:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 22:20:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 22:20:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 22:20:14 INFO - u'mozbase': [u'target.common.tests.zip'], 22:20:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 22:20:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 22:20:14 INFO - u'web-platform': [u'target.common.tests.zip', 22:20:14 INFO - u'target.web-platform.tests.zip'], 22:20:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 22:20:14 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 22:20:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.common.tests.zip 22:20:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 22:20:14 INFO - Fetch https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.common.tests.zip into memory 22:20:15 INFO - Content-Length response header: 58590993 22:20:15 INFO - Bytes received: 58590993 22:20:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 22:20:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.common.tests.zip 22:20:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 22:20:25 INFO - Fetch https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.common.tests.zip into memory 22:20:26 INFO - Content-Length response header: 58590993 22:20:26 INFO - Bytes received: 58590993 22:20:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.talos.tests.zip 22:20:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 22:20:37 INFO - Fetch https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.talos.tests.zip into memory 22:20:37 INFO - Content-Length response header: 14409411 22:20:37 INFO - Bytes received: 14409411 22:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:38 INFO - trying https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.tar.bz2 22:20:38 INFO - Downloading https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 22:20:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 22:20:39 INFO - Downloaded 63411088 bytes. 22:20:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.tar.bz2 22:20:39 INFO - mkdir: /builds/slave/test/properties 22:20:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:20:39 INFO - Writing to file /builds/slave/test/properties/build_url 22:20:39 INFO - Contents: 22:20:39 INFO - build_url:https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.tar.bz2 22:20:39 INFO - Running post-action listener: _resource_record_post_action 22:20:39 INFO - Running post-action listener: set_extra_try_arguments 22:20:39 INFO - [mozharness: 2017-05-12 05:20:39.481882Z] Finished download-and-extract step (success) 22:20:39 INFO - [mozharness: 2017-05-12 05:20:39.481979Z] Running populate-webroot step. 22:20:39 INFO - Running pre-action listener: _resource_record_pre_action 22:20:39 INFO - Running main action method: populate_webroot 22:20:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:20:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:20:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:20:39 INFO - '--tppagecycles', 22:20:39 INFO - '7'], 22:20:39 INFO - 'tests': ['tsvgm']}}, 22:20:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:20:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:20:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:20:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:20:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:20:39 INFO - 'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:20:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:20:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:20:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:20:39 INFO - 'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['damp', 'tps']}, 22:20:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:20:39 INFO - 'tests': ['damp', 'tps']}, 22:20:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['dromaeo_dom']}, 22:20:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:20:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:20:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:20:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['a11yr', 22:20:39 INFO - 'ts_paint', 22:20:39 INFO - 'tpaint', 22:20:39 INFO - 'sessionrestore', 22:20:39 INFO - 'sessionrestore_no_auto_restore', 22:20:39 INFO - 'tabpaint']}, 22:20:39 INFO - 'other-e10s': {'tests': ['a11yr', 22:20:39 INFO - 'ts_paint', 22:20:39 INFO - 'tpaint', 22:20:39 INFO - 'sessionrestore', 22:20:39 INFO - 'sessionrestore_no_auto_restore', 22:20:39 INFO - 'tabpaint']}, 22:20:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:20:39 INFO - 'ts_paint', 22:20:39 INFO - 'tpaint', 22:20:39 INFO - 'sessionrestore', 22:20:39 INFO - 'sessionrestore_no_auto_restore', 22:20:39 INFO - 'tabpaint']}, 22:20:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:20:39 INFO - 'ts_paint', 22:20:39 INFO - 'tpaint', 22:20:39 INFO - 'sessionrestore', 22:20:39 INFO - 'sessionrestore_no_auto_restore', 22:20:39 INFO - 'tabpaint']}, 22:20:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['a11yr', 22:20:39 INFO - 'ts_paint', 22:20:39 INFO - 'tpaint', 22:20:39 INFO - 'sessionrestore', 22:20:39 INFO - 'sessionrestore_no_auto_restore', 22:20:39 INFO - 'tabpaint']}, 22:20:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['a11yr', 22:20:39 INFO - 'ts_paint', 22:20:39 INFO - 'tpaint', 22:20:39 INFO - 'sessionrestore', 22:20:39 INFO - 'sessionrestore_no_auto_restore', 22:20:39 INFO - 'tabpaint']}, 22:20:39 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:20:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:20:39 INFO - 'bloom_basic_ref']}, 22:20:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['tsvgx', 22:20:39 INFO - 'tsvgr_opacity', 22:20:39 INFO - 'tart', 22:20:39 INFO - 'tscrollx', 22:20:39 INFO - 'cart', 22:20:39 INFO - 'tsvg_static']}, 22:20:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:20:39 INFO - 'tsvgr_opacity', 22:20:39 INFO - 'tart', 22:20:39 INFO - 'tscrollx', 22:20:39 INFO - 'cart', 22:20:39 INFO - 'tsvg_static']}, 22:20:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:20:39 INFO - 'talos_options': ['--disable-e10s'], 22:20:39 INFO - 'tests': ['tp5o']}, 22:20:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:20:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:20:39 INFO - 'talos_options': ['--disable-e10s', 22:20:39 INFO - '--xperf_path', 22:20:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:20:39 INFO - 'tests': ['tp5n']}, 22:20:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:20:39 INFO - 'talos_options': ['--xperf_path', 22:20:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:20:39 INFO - 'tests': ['tp5n']}}, 22:20:39 INFO - 'talos.zip': {'path': '', 22:20:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:20:39 INFO - Running post-action listener: _resource_record_post_action 22:20:39 INFO - [mozharness: 2017-05-12 05:20:39.495646Z] Finished populate-webroot step (success) 22:20:39 INFO - [mozharness: 2017-05-12 05:20:39.495734Z] Running create-virtualenv step. 22:20:39 INFO - Running pre-action listener: _resource_record_pre_action 22:20:39 INFO - Running main action method: create_virtualenv 22:20:39 INFO - Creating virtualenv /builds/slave/test/build/venv 22:20:39 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 22:20:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:20:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:20:39 INFO - Using real prefix '/usr' 22:20:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:20:40 INFO - Installing distribute.............................................................................................................................................................................................done. 22:20:42 INFO - Installing pip.................done. 22:20:42 INFO - Return code: 0 22:20:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:20:42 INFO - Reading from file tmpfile_stdout 22:20:42 INFO - Output received: 22:20:42 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) 22:20:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:42 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/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 22:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:20:42 INFO - Using env: {'DISPLAY': ':0', 22:20:42 INFO - 'HOME': '/home/cltbld', 22:20:42 INFO - 'LANG': 'en_US.UTF-8', 22:20:42 INFO - 'LANGUAGE': 'en_US:en', 22:20:42 INFO - 'LOGNAME': 'cltbld', 22:20:42 INFO - 'MAIL': '/var/mail/cltbld', 22:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:42 INFO - 'MOZ_NO_REMOTE': '1', 22:20:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:42 INFO - 'NO_EM_RESTART': '1', 22:20:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:42 INFO - 'PWD': '/builds/slave/test', 22:20:42 INFO - 'SHELL': '/bin/bash', 22:20:42 INFO - 'SHLVL': '1', 22:20:42 INFO - 'TERM': 'linux', 22:20:42 INFO - 'TMOUT': '86400', 22:20:42 INFO - 'USER': 'cltbld', 22:20:42 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:42 INFO - '_': '/tools/buildbot/bin/python'} 22:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:42 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 22:20:42 INFO - Cleaning up... 22:20:42 INFO - Return code: 0 22:20:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:42 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/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 22:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:20:42 INFO - Using env: {'DISPLAY': ':0', 22:20:42 INFO - 'HOME': '/home/cltbld', 22:20:42 INFO - 'LANG': 'en_US.UTF-8', 22:20:42 INFO - 'LANGUAGE': 'en_US:en', 22:20:42 INFO - 'LOGNAME': 'cltbld', 22:20:42 INFO - 'MAIL': '/var/mail/cltbld', 22:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:42 INFO - 'MOZ_NO_REMOTE': '1', 22:20:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:42 INFO - 'NO_EM_RESTART': '1', 22:20:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:42 INFO - 'PWD': '/builds/slave/test', 22:20:42 INFO - 'SHELL': '/bin/bash', 22:20:42 INFO - 'SHLVL': '1', 22:20:42 INFO - 'TERM': 'linux', 22:20:42 INFO - 'TMOUT': '86400', 22:20:42 INFO - 'USER': 'cltbld', 22:20:42 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:42 INFO - '_': '/tools/buildbot/bin/python'} 22:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:42 INFO - Downloading/unpacking psutil>=3.1.1 22:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:20:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:20:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:20:43 INFO - Installing collected packages: psutil 22:20:43 INFO - Running setup.py install for psutil 22:20:43 INFO - building 'psutil._psutil_linux' extension 22:20:43 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 22:20:44 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 22:20:44 INFO - building 'psutil._psutil_posix' extension 22:20:44 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 22:20:44 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 22:20:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:20:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:20:44 INFO - Successfully installed psutil 22:20:44 INFO - Cleaning up... 22:20:44 INFO - Return code: 0 22:20:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:44 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/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 22:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:20:44 INFO - Using env: {'DISPLAY': ':0', 22:20:44 INFO - 'HOME': '/home/cltbld', 22:20:44 INFO - 'LANG': 'en_US.UTF-8', 22:20:44 INFO - 'LANGUAGE': 'en_US:en', 22:20:44 INFO - 'LOGNAME': 'cltbld', 22:20:44 INFO - 'MAIL': '/var/mail/cltbld', 22:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:44 INFO - 'MOZ_NO_REMOTE': '1', 22:20:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:44 INFO - 'NO_EM_RESTART': '1', 22:20:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:44 INFO - 'PWD': '/builds/slave/test', 22:20:44 INFO - 'SHELL': '/bin/bash', 22:20:44 INFO - 'SHLVL': '1', 22:20:44 INFO - 'TERM': 'linux', 22:20:44 INFO - 'TMOUT': '86400', 22:20:44 INFO - 'USER': 'cltbld', 22:20:44 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:44 INFO - '_': '/tools/buildbot/bin/python'} 22:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:20:44 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 22:20:44 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 22:20:44 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 22:20:44 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 22:20:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:20:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:20:45 INFO - Installing collected packages: mozsystemmonitor 22:20:45 INFO - Running setup.py install for mozsystemmonitor 22:20:45 INFO - Successfully installed mozsystemmonitor 22:20:45 INFO - Cleaning up... 22:20:45 INFO - Return code: 0 22:20:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 22:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:20:45 INFO - Using env: {'DISPLAY': ':0', 22:20:45 INFO - 'HOME': '/home/cltbld', 22:20:45 INFO - 'LANG': 'en_US.UTF-8', 22:20:45 INFO - 'LANGUAGE': 'en_US:en', 22:20:45 INFO - 'LOGNAME': 'cltbld', 22:20:45 INFO - 'MAIL': '/var/mail/cltbld', 22:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:45 INFO - 'MOZ_NO_REMOTE': '1', 22:20:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:45 INFO - 'NO_EM_RESTART': '1', 22:20:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:45 INFO - 'PWD': '/builds/slave/test', 22:20:45 INFO - 'SHELL': '/bin/bash', 22:20:45 INFO - 'SHLVL': '1', 22:20:45 INFO - 'TERM': 'linux', 22:20:45 INFO - 'TMOUT': '86400', 22:20:45 INFO - 'USER': 'cltbld', 22:20:45 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:45 INFO - '_': '/tools/buildbot/bin/python'} 22:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:46 INFO - Downloading/unpacking jsonschema==2.5.1 22:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:20:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:47 INFO - Downloading functools32-3.2.3-2.tar.gz 22:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:20:47 INFO - warning: no files found matching '*.txt' 22:20:47 INFO - no previously-included directories found matching 'build' 22:20:47 INFO - no previously-included directories found matching 'dist' 22:20:47 INFO - no previously-included directories found matching '.git*' 22:20:47 INFO - Installing collected packages: jsonschema, functools32 22:20:47 INFO - Running setup.py install for functools32 22:20:47 INFO - warning: no files found matching '*.txt' 22:20:47 INFO - no previously-included directories found matching 'build' 22:20:47 INFO - no previously-included directories found matching 'dist' 22:20:47 INFO - no previously-included directories found matching '.git*' 22:20:47 INFO - Successfully installed jsonschema functools32 22:20:47 INFO - Cleaning up... 22:20:47 INFO - Return code: 0 22:20:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 22:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:20:47 INFO - Using env: {'DISPLAY': ':0', 22:20:47 INFO - 'HOME': '/home/cltbld', 22:20:47 INFO - 'LANG': 'en_US.UTF-8', 22:20:47 INFO - 'LANGUAGE': 'en_US:en', 22:20:47 INFO - 'LOGNAME': 'cltbld', 22:20:47 INFO - 'MAIL': '/var/mail/cltbld', 22:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:47 INFO - 'MOZ_NO_REMOTE': '1', 22:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:47 INFO - 'NO_EM_RESTART': '1', 22:20:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:47 INFO - 'PWD': '/builds/slave/test', 22:20:47 INFO - 'SHELL': '/bin/bash', 22:20:47 INFO - 'SHLVL': '1', 22:20:47 INFO - 'TERM': 'linux', 22:20:47 INFO - 'TMOUT': '86400', 22:20:47 INFO - 'USER': 'cltbld', 22:20:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:47 INFO - '_': '/tools/buildbot/bin/python'} 22:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:20:47 INFO - Cleaning up... 22:20:47 INFO - Return code: 0 22:20:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 22:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:20:47 INFO - Using env: {'DISPLAY': ':0', 22:20:47 INFO - 'HOME': '/home/cltbld', 22:20:47 INFO - 'LANG': 'en_US.UTF-8', 22:20:47 INFO - 'LANGUAGE': 'en_US:en', 22:20:47 INFO - 'LOGNAME': 'cltbld', 22:20:47 INFO - 'MAIL': '/var/mail/cltbld', 22:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:47 INFO - 'MOZ_NO_REMOTE': '1', 22:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:47 INFO - 'NO_EM_RESTART': '1', 22:20:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:47 INFO - 'PWD': '/builds/slave/test', 22:20:47 INFO - 'SHELL': '/bin/bash', 22:20:47 INFO - 'SHLVL': '1', 22:20:47 INFO - 'TERM': 'linux', 22:20:47 INFO - 'TMOUT': '86400', 22:20:47 INFO - 'USER': 'cltbld', 22:20:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:47 INFO - '_': '/tools/buildbot/bin/python'} 22:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:47 INFO - Downloading/unpacking blobuploader==1.2.4 22:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:49 INFO - Downloading blobuploader-1.2.4.tar.gz 22:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:20:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:20:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:49 INFO - Downloading docopt-0.6.1.tar.gz 22:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:20:49 INFO - Installing collected packages: blobuploader, requests, docopt 22:20:49 INFO - Running setup.py install for blobuploader 22:20:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:20:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:20:49 INFO - Running setup.py install for requests 22:20:50 INFO - Running setup.py install for docopt 22:20:50 INFO - Successfully installed blobuploader requests docopt 22:20:50 INFO - Cleaning up... 22:20:50 INFO - Return code: 0 22:20:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:50 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/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:50 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:20:50 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:20:50 INFO - Using env: {'DISPLAY': ':0', 22:20:50 INFO - 'HOME': '/home/cltbld', 22:20:50 INFO - 'LANG': 'en_US.UTF-8', 22:20:50 INFO - 'LANGUAGE': 'en_US:en', 22:20:50 INFO - 'LOGNAME': 'cltbld', 22:20:50 INFO - 'MAIL': '/var/mail/cltbld', 22:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:50 INFO - 'MOZ_NO_REMOTE': '1', 22:20:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:50 INFO - 'NO_EM_RESTART': '1', 22:20:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:50 INFO - 'PWD': '/builds/slave/test', 22:20:50 INFO - 'SHELL': '/bin/bash', 22:20:50 INFO - 'SHLVL': '1', 22:20:50 INFO - 'TERM': 'linux', 22:20:50 INFO - 'TMOUT': '86400', 22:20:50 INFO - 'USER': 'cltbld', 22:20:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:50 INFO - '_': '/tools/buildbot/bin/python'} 22:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:20:50 INFO - Running setup.py (path:/tmp/pip-xrXwQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:20:50 INFO - Running setup.py (path:/tmp/pip-IGMRsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:20:50 INFO - Running setup.py (path:/tmp/pip-fzdpOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:20:50 INFO - Running setup.py (path:/tmp/pip-B2TXz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:20:50 INFO - Running setup.py (path:/tmp/pip-l3C0xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:20:50 INFO - Running setup.py (path:/tmp/pip-CUjVTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:20:50 INFO - Running setup.py (path:/tmp/pip-mC4r7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:20:50 INFO - Running setup.py (path:/tmp/pip-93bSL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:20:51 INFO - Running setup.py (path:/tmp/pip-3pZt9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:20:51 INFO - Running setup.py (path:/tmp/pip-kX99UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:20:51 INFO - Running setup.py (path:/tmp/pip-iL3h7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:20:51 INFO - Running setup.py (path:/tmp/pip-NGbDQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:20:51 INFO - Running setup.py (path:/tmp/pip-Hix0_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:20:51 INFO - Running setup.py (path:/tmp/pip-0kvgid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:51 INFO - Running setup.py (path:/tmp/pip-YMsPso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:20:51 INFO - Running setup.py (path:/tmp/pip-yG4D_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:20:51 INFO - Running setup.py (path:/tmp/pip-JLAUW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:20:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:20:51 INFO - Running setup.py install for manifestparser 22:20:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:20:51 INFO - Running setup.py install for mozcrash 22:20:51 INFO - Running setup.py install for mozdebug 22:20:51 INFO - Running setup.py install for mozdevice 22:20:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Running setup.py install for mozfile 22:20:52 INFO - Running setup.py install for mozhttpd 22:20:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Running setup.py install for mozinfo 22:20:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Running setup.py install for mozInstall 22:20:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Running setup.py install for mozleak 22:20:52 INFO - Running setup.py install for mozlog 22:20:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Running setup.py install for moznetwork 22:20:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:20:52 INFO - Running setup.py install for mozprocess 22:20:52 INFO - Running setup.py install for mozprofile 22:20:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:20:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:20:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:20:53 INFO - Running setup.py install for mozrunner 22:20:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:20:53 INFO - Running setup.py install for mozscreenshot 22:20:53 INFO - Running setup.py install for moztest 22:20:53 INFO - Running setup.py install for mozversion 22:20:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:20:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:20:53 INFO - Cleaning up... 22:20:53 INFO - Return code: 0 22:20:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:53 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/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:53 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:20:53 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:20:53 INFO - Using env: {'DISPLAY': ':0', 22:20:53 INFO - 'HOME': '/home/cltbld', 22:20:53 INFO - 'LANG': 'en_US.UTF-8', 22:20:53 INFO - 'LANGUAGE': 'en_US:en', 22:20:53 INFO - 'LOGNAME': 'cltbld', 22:20:53 INFO - 'MAIL': '/var/mail/cltbld', 22:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:53 INFO - 'MOZ_NO_REMOTE': '1', 22:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:53 INFO - 'NO_EM_RESTART': '1', 22:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:53 INFO - 'PWD': '/builds/slave/test', 22:20:53 INFO - 'SHELL': '/bin/bash', 22:20:53 INFO - 'SHLVL': '1', 22:20:53 INFO - 'TERM': 'linux', 22:20:53 INFO - 'TMOUT': '86400', 22:20:53 INFO - 'USER': 'cltbld', 22:20:53 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:53 INFO - '_': '/tools/buildbot/bin/python'} 22:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:20:53 INFO - Running setup.py (path:/tmp/pip-nC96cA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:20:53 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)) 22:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:20:53 INFO - Running setup.py (path:/tmp/pip-u1sx19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:20:53 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)) 22:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:20:53 INFO - Running setup.py (path:/tmp/pip-xJjBUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:20:53 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)) 22:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:20:53 INFO - Running setup.py (path:/tmp/pip-7vsjyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:20:54 INFO - Running setup.py (path:/tmp/pip-kVi9Ri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:20:54 INFO - Running setup.py (path:/tmp/pip-KqidFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:20:54 INFO - Running setup.py (path:/tmp/pip-c7qUZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:20:54 INFO - Running setup.py (path:/tmp/pip-4Bd1YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:20:54 INFO - Running setup.py (path:/tmp/pip-PZIwtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:20:54 INFO - Running setup.py (path:/tmp/pip-nriy58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:20:54 INFO - Running setup.py (path:/tmp/pip-FuiQzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:20:54 INFO - Running setup.py (path:/tmp/pip-0Zgd7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:20:54 INFO - Running setup.py (path:/tmp/pip-mHe6ZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:20:54 INFO - Running setup.py (path:/tmp/pip-3Q2Vxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:54 INFO - Running setup.py (path:/tmp/pip-BLJcpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:20:54 INFO - Running setup.py (path:/tmp/pip-bUvOwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:20:54 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)) 22:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:20:54 INFO - Running setup.py (path:/tmp/pip-JFDZUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:20:54 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)) 22:20:54 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)) 22:20:54 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)) 22:20:54 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)) 22:20:54 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:54 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:20: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 22:20: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 22:20: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 22:20: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 22:20:56 INFO - Downloading blessings-1.6.tar.gz 22:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:20:56 INFO - Installing collected packages: blessings 22:20:56 INFO - Running setup.py install for blessings 22:20:56 INFO - Successfully installed blessings 22:20:56 INFO - Cleaning up... 22:20:56 INFO - Return code: 0 22:20:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:20:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:56 INFO - Reading from file tmpfile_stdout 22:20:56 INFO - Current package versions: 22:20:56 INFO - argparse == 1.2.1 22:20:56 INFO - blessings == 1.6 22:20:56 INFO - blobuploader == 1.2.4 22:20:56 INFO - docopt == 0.6.1 22:20:56 INFO - functools32 == 3.2.3-2 22:20:56 INFO - jsonschema == 2.5.1 22:20:56 INFO - manifestparser == 1.1 22:20:56 INFO - mozInstall == 1.12 22:20:56 INFO - mozcrash == 1.0 22:20:56 INFO - mozdebug == 0.1 22:20:56 INFO - mozdevice == 0.50 22:20:56 INFO - mozfile == 1.2 22:20:56 INFO - mozhttpd == 0.7 22:20:56 INFO - mozinfo == 0.9 22:20:56 INFO - mozleak == 0.1 22:20:56 INFO - mozlog == 3.4 22:20:56 INFO - moznetwork == 0.27 22:20:56 INFO - mozprocess == 0.25 22:20:56 INFO - mozprofile == 0.28 22:20:56 INFO - mozrunner == 6.13 22:20:56 INFO - mozscreenshot == 0.1 22:20:56 INFO - mozsystemmonitor == 0.3 22:20:56 INFO - moztest == 0.8 22:20:56 INFO - mozversion == 1.4 22:20:56 INFO - psutil == 3.1.1 22:20:56 INFO - requests == 1.2.3 22:20:56 INFO - wsgiref == 0.1.2 22:20:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:56 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/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:56 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:20:56 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:20:56 INFO - Using env: {'DISPLAY': ':0', 22:20:56 INFO - 'HOME': '/home/cltbld', 22:20:56 INFO - 'LANG': 'en_US.UTF-8', 22:20:56 INFO - 'LANGUAGE': 'en_US:en', 22:20:56 INFO - 'LOGNAME': 'cltbld', 22:20:56 INFO - 'MAIL': '/var/mail/cltbld', 22:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:56 INFO - 'MOZ_NO_REMOTE': '1', 22:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:56 INFO - 'NO_EM_RESTART': '1', 22:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:56 INFO - 'PWD': '/builds/slave/test', 22:20:56 INFO - 'SHELL': '/bin/bash', 22:20:56 INFO - 'SHLVL': '1', 22:20:56 INFO - 'TERM': 'linux', 22:20:56 INFO - 'TMOUT': '86400', 22:20:56 INFO - 'USER': 'cltbld', 22:20:56 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:56 INFO - '_': '/tools/buildbot/bin/python'} 22:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 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)) 22:20:56 INFO - Cleaning up... 22:20:56 INFO - Return code: 0 22:20:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x20881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23fa770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, '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': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', '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 22:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:20:56 INFO - Using env: {'DISPLAY': ':0', 22:20:56 INFO - 'HOME': '/home/cltbld', 22:20:56 INFO - 'LANG': 'en_US.UTF-8', 22:20:56 INFO - 'LANGUAGE': 'en_US:en', 22:20:56 INFO - 'LOGNAME': 'cltbld', 22:20:56 INFO - 'MAIL': '/var/mail/cltbld', 22:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:56 INFO - 'MOZ_NO_REMOTE': '1', 22:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:56 INFO - 'NO_EM_RESTART': '1', 22:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:56 INFO - 'PWD': '/builds/slave/test', 22:20:56 INFO - 'SHELL': '/bin/bash', 22:20:56 INFO - 'SHLVL': '1', 22:20:56 INFO - 'TERM': 'linux', 22:20:56 INFO - 'TMOUT': '86400', 22:20:56 INFO - 'USER': 'cltbld', 22:20:56 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:56 INFO - '_': '/tools/buildbot/bin/python'} 22:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:20:56 INFO - Cleaning up... 22:20:56 INFO - Return code: 0 22:20:56 INFO - Running post-action listener: _resource_record_post_action 22:20:56 INFO - Running post-action listener: _start_resource_monitoring 22:20:56 INFO - Starting resource monitoring. 22:20:56 INFO - [mozharness: 2017-05-12 05:20:56.970190Z] Finished create-virtualenv step (success) 22:20:56 INFO - [mozharness: 2017-05-12 05:20:56.970708Z] Running install step. 22:20:56 INFO - Running pre-action listener: _resource_record_pre_action 22:20:56 INFO - Running main action method: install 22:20:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:57 INFO - Reading from file tmpfile_stdout 22:20:57 INFO - Detecting whether we're running mozinstall >=1.0... 22:20:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:20:57 INFO - Reading from file tmpfile_stdout 22:20:57 INFO - Output received: 22:20:57 INFO - Usage: mozinstall [options] installer 22:20:57 INFO - Options: 22:20:57 INFO - -h, --help show this help message and exit 22:20:57 INFO - -d DEST, --destination=DEST 22:20:57 INFO - Directory to install application into. [default: 22:20:57 INFO - "/builds/slave/test"] 22:20:57 INFO - --app=APP Application being installed. [default: firefox] 22:20:57 INFO - mkdir: /builds/slave/test/build/application 22:20:57 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'] 22:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 22:21:13 INFO - Reading from file tmpfile_stdout 22:21:13 INFO - Output received: 22:21:13 INFO - /builds/slave/test/build/application/firefox/firefox 22:21:13 INFO - Running post-action listener: _resource_record_post_action 22:21:13 INFO - [mozharness: 2017-05-12 05:21:13.135498Z] Finished install step (success) 22:21:13 INFO - [mozharness: 2017-05-12 05:21:13.135640Z] Running run-tests step. 22:21:13 INFO - Running pre-action listener: _resource_record_pre_action 22:21:13 INFO - Running main action method: run_tests 22:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:21:13 INFO - Python 2.7.3 22:21:13 INFO - Return code: 0 22:21:13 INFO - grabbing minidump binary from tooltool 22:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 22:21:13 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 0x23f7a30>, '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 0x23f87f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23f8ca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:21:13 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 22:21:13 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 22:21:13 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 22:21:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:21:13 INFO - Return code: 0 22:21:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:21:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:21:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:21:13 INFO - ENV: RUST_BACKTRACE is now 1 22:21:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:21:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 22:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 22:21:13 INFO - Using env: {'DISPLAY': ':0', 22:21:13 INFO - 'HOME': '/home/cltbld', 22:21:13 INFO - 'LANG': 'en_US.UTF-8', 22:21:13 INFO - 'LANGUAGE': 'en_US:en', 22:21:13 INFO - 'LOGNAME': 'cltbld', 22:21:13 INFO - 'MAIL': '/var/mail/cltbld', 22:21:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:21:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:13 INFO - 'MOZ_NO_REMOTE': '1', 22:21:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:21:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:13 INFO - 'NO_EM_RESTART': '1', 22:21:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:13 INFO - 'PWD': '/builds/slave/test', 22:21:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:21:13 INFO - 'RUST_BACKTRACE': '1', 22:21:13 INFO - 'SHELL': '/bin/bash', 22:21:13 INFO - 'SHLVL': '1', 22:21:13 INFO - 'TERM': 'linux', 22:21:13 INFO - 'TMOUT': '86400', 22:21:13 INFO - 'USER': 'cltbld', 22:21:13 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126', 22:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:13 INFO - '_': '/tools/buildbot/bin/python'} 22:21:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 22:21:13 INFO - mozversion application_buildid: 20170512044447 22:21:13 INFO - mozversion application_changeset: 8a7d0b15595f9916123848ca906f29c62d4914c9 22:21:13 INFO - mozversion application_display_name: Nightly 22:21:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:21:13 INFO - mozversion application_name: Firefox 22:21:13 INFO - mozversion application_remotingname: firefox 22:21:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:21:13 INFO - mozversion application_vendor: Mozilla 22:21:13 INFO - mozversion application_version: 55.0a1 22:21:13 INFO - mozversion platform_buildid: 20170512044447 22:21:13 INFO - mozversion platform_changeset: 8a7d0b15595f9916123848ca906f29c62d4914c9 22:21:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:21:13 INFO - mozversion platform_version: 55.0a1 22:21:13 INFO - using testdate: 1494566473 22:21:13 INFO - actual date: 1494566473 22:21:13 INFO - starting webserver on 'localhost:41511' 22:21:13 INFO - SUITE-START | Running 2 tests 22:21:13 INFO - TEST-START | dromaeo_css 22:21:13 INFO - operating with platform_type : linux_ 22:21:13 INFO - Initialising browser for dromaeo_css test... 22:21:13 INFO - TEST-INFO | started process 29742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTiU_K5/profile http://localhost:41511/getInfo.html) 22:21:18 INFO - PID 29742 | __metrics Screen width/height:1600/1200 22:21:18 INFO - PID 29742 | colorDepth:24 22:21:18 INFO - PID 29742 | Browser inner width/height: 1024/695 22:21:18 INFO - PID 29742 | __metrics 22:21:19 INFO - PID 29742 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:21:19 INFO - PID 29742 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:21:20 INFO - TEST-INFO | 29742: exit 0 22:21:20 INFO - Browser initialized. 22:21:20 INFO - Running cycle 1/1 for dromaeo_css test... 22:21:20 INFO - TEST-INFO | started process 29838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTiU_K5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:21:32 INFO - PID 29838 | RSS: Main: 148463616 22:21:32 INFO - PID 29838 | 22:24:38 INFO - PID 29838 | Cycle 1(1): loaded http://localhost:41511/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41511/tests/dromaeo/cssquery-ext.html) 22:24:39 INFO - PID 29838 | RSS: Main: 250691584 22:24:39 INFO - PID 29838 | 22:27:42 INFO - PID 29838 | Cycle 1(1): loaded http://localhost:41511/tests/dromaeo/cssquery-ext.html (next: http://localhost:41511/tests/dromaeo/cssquery-jquery.html) 22:27:42 INFO - PID 29838 | RSS: Main: 229310464 22:27:42 INFO - PID 29838 | 22:30:44 INFO - PID 29838 | Cycle 1(1): loaded http://localhost:41511/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41511/tests/dromaeo/cssquery-mootools.html) 22:30:45 INFO - PID 29838 | RSS: Main: 214196224 22:30:45 INFO - PID 29838 | 22:33:47 INFO - PID 29838 | Cycle 1(1): loaded http://localhost:41511/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41511/tests/dromaeo/cssquery-prototype.html) 22:33:48 INFO - PID 29838 | RSS: Main: 176111616 22:33:48 INFO - PID 29838 | 22:36:51 INFO - PID 29838 | Cycle 1(1): loaded http://localhost:41511/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41511/tests/dromaeo/cssquery-yui.html) 22:36:51 INFO - PID 29838 | RSS: Main: 179449856 22:36:51 INFO - PID 29838 | 22:39:53 INFO - PID 29838 | Cycle 1(1): loaded http://localhost:41511/tests/dromaeo/cssquery-yui.html (next: http://localhost:41511/tests/dromaeo/cssquery-dojo.html) 22:39:54 INFO - PID 29838 | RSS: Main: 266268672 22:39:54 INFO - PID 29838 | 22:39:54 INFO - PID 29838 | __start_tp_report 22:39:54 INFO - PID 29838 | _x_x_mozilla_page_load 22:39:54 INFO - PID 29838 | _x_x_mozilla_page_load_details 22:39:54 INFO - PID 29838 | |i|pagename|runs| 22:39:54 INFO - PID 29838 | |0;dojo.html;2704.1617098699403;2711.7017128115917;2744.958825617616;2751.876165572549;2754.476649436608;4932.778024988876;5069.822556210534;5159.484051594844;5200.912719915286;5226.895462090756;5076.111680455915;5111.1566591512355;5119.692818430897;5164.096283150452;5180.792768289273;4211.368294755781;4451.376807246988;4554.567316104964;4610.631149508048;4624.005838744669;4618.007128467379;4628.675992680497;4670.252759558472;4689.062187562484;4695.366125573055;4477.731336119843;4539.16025535277;4540;4550.203714349976;4594.333821595883;4599.195140850338;4609.884752881171;4612.607928326062;4633.698809577367;4660;4542.38677778499;4560.179167749804;4600.378948841931;4617.815287388534;4632.698874573176;5011.874703132415;5079.593632509391;5089.6691715038405;5161.664491808058;5174.172132458789;5505.174223866412;5550.47270509301;5585.022621041301;5594.860128496779;5605.831825045255;5513.09034009388;5532.944670553283;5533.170024496318;5535.501804837584;5546.084895992157;5042.092423363756;5157.329547158845;5160.52472813761;5162.328897243372;5216.7913283469;5619;5671.291088613923;5753.108268218432;5756.1653560233535;5765.509931655773;5360.41035486104;5424.538914192259;5426.186072089139;5435.211894275308;5438;5461.317335333083;5515.227868098469;5521.282233309725;5554.750036248379;5583.525400340935;5328.573714102862;5403.189521571796;5406.297181366397;5465.9180112298345;5505.229267902497;5495.0383682855345;5495.450454954473;5501.037318469252;5501.972490137524;5536.307961504812;4269.978650106792;4275.102228532043;4278.101598664253;4316.568343165658;4325.740455572675;3697.685696715809;3722.2555488902326;3728.2729867676085;3736.0473079365133;3763.397856342972;4746.738929358849;4747.0505898820375;4749.192637251606;4776.808927642855;4797.832075877397;5552.11166213412;5592.832215033555;5594.888102238014;5605.299337582802;5649.887002259932;4606.723596584216;4688.789004494806;4698.483166851644;4753.5959443447;4759.309900064472;4922.90154196914;5018.322526458955;5018.673786203885;5024.070546948753;5027.044861476381;5028.723420211777;5215.322008138917;5230.084735171254;5253.658512196695;5255.500727430888;5466.34403871538;5502.064649009598;5513.862153446035;5529.4470552944385;5608.018596745472;5173.922391164211;5211.557017653542;5218.217267409919;5235.738213089254;5249.842504724865;5514.531264842373;5519.806806761744;5521.337439507285;5521.944780552143;5556.083246264321;5445.809396671098;5459.972700136474;5482.259894914136;5488.780448782004;5499.752511137089;5385.461453854583;5388.164834450667;5423.4034256231;5448.427915069019;5448.83653490396;5432.945670543402;5436.0758671026815;5441.455854414527;5443.836684899459;5494.670319780826;5167.22491626259;5270.156774916149;5294.549963253013;5367.704776237344;5376.435474275145;5483.204935284281;5485.451454854483;5489.615726899079;5519.420460851553;5533.142362933751;5278.129108697022;5327.706976116351;5330.067238233371;5333.493318134771;5380.139177731544 22:39:54 INFO - PID 29838 | |1;ext.html;617.4720613875137;646.7671638210244;670.758526930305;999.1058003087237;1012.5848402155118;25477.74522254777;32187.356252874928;32714.836425817837;32880.506792398075;32938.01185964422;34796.65203347966;35039.4744078839;35267.647323526755;35276;35285.823570882174;5215.530602245797;5378.489043540857;6859.622720750369;7012.088428504288;7015.158181018285;6130;6882.449404047664;6896.620685862275;6900.6204658743745;6901.965490172543;6905.654717264142;7005.614691191982;7008.159020917497;7008.194057683355;7013.193482749472;6892.310768923093;6924.619145946971;6936.618485983269;6945.4096401805655;6951.89572156418;6782.491313151533;6846.075779769717;6869.141357330333;6873.243943166248;6875.759348422831;30778.23054423635;31037.844810776027;31074.378512429852;31131.066068017997;31167.22081947947;3011.9397612047856;3065.111117775842;3065.340951695396;3065.6167979002626;3066.080175947207;23256.53486930269;23316.65025024626;23353.416164595852;23361;23375.181868634518;3091.5980922480157;3147.3862596793406;3148.685131486856;3156.794808337451;3162.2884850908363;136751;137271;137413;137631;137850.31074844662;39666.20667586632;39753.20493590112;39769;39794.80102599468;39804.601953980666;41134.58865411367;41502.58497415048;41653.791731041754;41658.791706041884;41698.58301417008;10883.346999180074;10966.780664386828;10969.890301097046;10978.835317470244;10980.835287470694;8053.31546818527;8179.263866252066;8747.781305467413;8752.56237188138;8757.956210219036;10965.232433729721;10987.670369888756;11006.394648294421;11159.72100697489;11239.269447485887;4892.290617860391;4900.730459824744;5015.1975683890405;5032.7483625819295;5041.8487445376695;876.4960147914975;888.6223355074197;893.9865902011475;896.2158111652304;922.718570835888;19573.804261957197;20302.492437689176;20444.182232710824;20473.283435079702;20854.37436876896;947.2564037230878;958.736347504428;982.4547376206132;985.4629227071264;989.7970915962211;10504.63233786829;10504.684859454228;10870.782584348268;10871.945640271748;11020.614278500214;162139;162201.18899405663;162741.18629407015;162922;163020;37791.62208377936;38039;38139.80930095332;38141;38232.80883595564;1156.3351073132983;1206.6018213989362;1216.087934049463;1217.9634610961684;1220.8962238209851;18528.879381273353;18951.526211844815;19695.901520492305;19880.90059549693;20088.296909608678;27646.032388866293;27719.722802771714;27745.722542775122;27760.583591245744;27791;140850;140913;140991;141213;141450;23442.296731098093;23850.88074559616;26503.33741656397;26821.463570728865;27375.589366159922;30698;30811.075667730005;31511.842440788565;32192.517112243804;32717.18207044843;24807.379815504755;25248.017112556096;25358.239252822445;25549.361265967756;26801.731982680703;24522.87738561296;24903;25132.246032619052;25456.23631291064;26599.202023939313;28036.299092522033;28095.578566321114;28156.577651334836;28182.15453536315;28194.718052819208;24316.878415607807;24665.63001555014;24738;26645.600315995664;27078.72921270762;20950.161993520087;21582.244621438174;21867.67198491992;21897.67153492667;21947.780522194575 22:39:54 INFO - PID 29838 | |2;jquery.html;407.694229328004;411.15917947796754;426.9487661480622;435.8343829344849;444.5110378583556;16206.189690515486;17095;17630.206640701166;17666.558336041606;17695.115244237786;16092.436764713237;16134.435294764686;16177.272022758974;16283;17486;5361.498780341511;5447.45525447455;5462.672239665632;5616.747246373902;5622.072358060915;5433.1035379162395;5511.559075273988;5512.944870551303;5539.141433077859;5545.805896793593;5277.736113194344;5405.351357837065;5507.834764957038;5513.255710479074;5519.972400137994;5296.232046353276;5418.593605479605;5424.674519528821;5428.511433970942;5433.049216387156;4781.3545171401765;4857.562819346241;4912.778924948386;4922.729249891255;4977.054359671687;19972.300969466;20577.897110514503;20677.27629532959;20833.89583052075;20949.16203351864;1622.4078211452734;1664.8168701442833;1677.1111311005186;1678.2196278730448;1680.7058764716126;13462.596122116352;14147.221902795342;14169.504067357593;14221.431142754278;14361.640958976004;2033.2680235115347;2056.3728062940795;2088.1021160900805;2106.147010460766;2107.3045894854663;80148.59925700392;80623;80957.19042809615;81544.9999999994;82250.58874705648;10250.333728307636;10455.320404173857;10655.946720266504;10794.73013174677;10822.404767737693;15029.473968411272;15052.118485189352;15074.773878391832;15077.095374277578;15078.321475533627;6649.202095748541;6946.37482626566;6969.6166710830375;7032.402245809163;7078.256783037707;6199.380061993855;6203.503719702413;6392.7123279452535;6395.488360931115;6395.648239346788;6557.573757705733;6694.665266736643;6712.96643516789;6736.157980252468;6881.346272104142;1979.7921218272163;1988.8110629490175;2010.8290795282273;2019.9394018179185;2026.7669218040114;844.8394804987033;886.778305423644;887.1527691055027;889.6263569300779;914.9496777677158;16169.35322587107;16425.425110121087;16429.424970126227;16563.83436165647;17144.571385715455;869.3523325122824;871.4466313890725;875.1161327059716;878.9868151977726;896.8565029595234;8734.388592798443;9125.133112354448;9167.816643667089;9338.673146439842;9346.11211934865;338699;345002;346113;348376;353428;76395.85406218912;78467.60766196133;78511.60744196242;78691.60654196692;81239.5938020306;1044.289882879634;1062.1768129699544;1102.6691992402248;1106.181425744943;1107.302399488301;16380.672386551963;16717.74923376126;16781.748273775658;17151.142442878056;17234.482965511055;38460.80769596134;38484;38603.80698096604;39064.6093539061;40220.79889600533;46908.76545617387;48007.759961201366;48070.75964620154;48343.27485087797;48411.27383089186;8065;8187;8258.793530161794;8269.21442462965;8494;11806.291622502677;11873.406329683308;12032.75934481323;12237.510499580274;12457.1902826316;10764.838527422251;10977.780444390908;11180.832287515532;11453;11453.141014423785;9997.700068997941;10147.34042287249;10191.337563058378;10285.897141028494;10413.42726150069;10795.892041079804;10860.891391085988;11095.001449869227;11493.770124597628;11541.249818761753;7982.760517184493;8105.594720263845;8176.264136227768;8264.710735124241;8386.245237928615;10710.678679639623;10835.566577336818;10992.44568934948;11098.944505277745;11220.66338009861 22:39:54 INFO - PID 29838 | |3;mootools.html;1113.9944300278498;1268.479923231475;1307.3136603283276;1322.067942100819;1328.5283724277874;8311.958440207798;10190.439525826077;10518.368897866114;10527.578896844136;10527.63153289635;10262;10346.896531034687;10361.792764144731;10362.585496580128;10368.688939331812;1903.9333623323187;1972.9802701973013;1974.82226599606;2214.944626384341;2251.684764133023;1960.7745109312468;2169.6853956176346;2279.9658005129936;2284.6001949658744;2300.0454811253308;2245.113180293783;2251.3583628665833;2252.1329288223988;2256.5825322315422;2259.6836442898007;2086.5722526882028;2126.1176611706123;2128.776478469754;2130.8508404411696;2132.1471411435396;2077.4079387374677;2118.555103428282;2125.234915430444;2126.904289306985;2131.232756207764;9907.554160062815;10109.949450252776;10135.138513226384;10137.138343240833;10142.290039697224;5457.345118585756;5638.2670252866865;5647.152927060933;5651;5654.971725141348;3365.6802603752603;3488.7557870949045;3563.1092226943265;3581.6239294874185;3599.3881040223223;1068.8931106889327;1087;1103.7130346110068;1162.5698491558178;1166.4575972172895;14899.627509312353;14943.252837358197;15064.397424103023;15079.095254284777;15084.17037062961;4744.833930812405;4854.988887183873;5013.849584512471;5076.974615126901;5174.223866420067;12568.057395695176;12570.308633025088;12574.81137782934;12586.68533286657;12587.93706031464;5623.015972204929;5713.314402271754;5731.340895797037;5732.627379220337;5736.627119237236;5082.313887625197;5115.8721031973755;5135.717535535507;5148.819791307286;5161.66449180802;4123.6912966977325;4146.129312844276;4170.709498290854;4192.601702838226;4195.517515485758;1338.5783478204335;1355.9322033898266;1368.2679766325168;1371.5131128449457;1374.3471850870956;680.425040840489;703.6235613946515;703.781827633425;719.9928000720031;722.4148439763808;5803.82588522345;5905.291365036223;5914.8817023659285;5978.163057172;6109.450149486479;526.2500936165948;541.7453796715538;542.5496837624762;542.940276569577;797.6849144587972;3778.263238668433;3830.80845957701;3854.402567602026;3902.746321489094;3996.0609256824623;12749.681257968625;12772.233665980071;13133.737325253633;13289.069765116348;13524.864751352558;13043.28261945602;13106.279154646396;13118.672033199056;13144.539941102015;13316.001299902353;7400.222976587488;7514.849703005909;7548.584827834522;7590.354819840265;7598.620068996639;6141.631502109888;6228.252609686685;6273.905891411541;6304.432601065926;6346.904796427965;11743.177977541833;11869.525218991144;11875.406229688308;11959.402029898645;11978.940105299418;12063.577774777841;12069.189621556836;12122.818157727464;12217.755644887231;12378.44297006655;7378.29906158914;7567.697292108254;8159.143289954469;8291.212334828182;8338.624761885609;8259.793505162419;9579.185769209416;9679.564419601276;9683.66107186245;9694.660686875926;7894.328982036362;8520.3183745299;8747;8924.152205540464;9002;8199.303059239794;8577.614007369559;8621.482711037357;8774.429662071945;8801.207891289558;7566.470347075652;7637;7660.846783064419;7674.769756907079;7704.26809453101;5384.811531596375;5460.890782184255;5476.151196564539;5531.170324451204;5594.552435805044;5243.370795504564;5351.438098999549;5354.411014788292;5425.213344065168;5442.863928401822 22:39:54 INFO - PID 29838 | |4;prototype.html;379.06560328789527;385.54120596490156;390.2877249020538;390.5040598439981;393.04490089083527;5157.277981082647;5179.948200517993;5191.506806853347;5196.168613021912;5336.17289320155;5113.130767769479;5155.845324640258;5167.199084141964;5206.5574426173725;5226.588821018332;3985.3822657488063;4044.7370920890194;4168.62482376586;4188.183304255671;4191.622753952143;4173.582641735832;4186.267403204442;4186.28833098374;4188.518320393148;4189.7695626740515;4145.979270103645;4148.278464437255;4166.333386658135;4188.141431006637;4194;4055.858044968412;4153.418521407006;4179.916401671965;4193.791749311794;4194.454720886296;3760;3788.7726736395903;3801.961980380216;3804.258169656918;3823.3118038753014;5074.980386669962;5086.542211200973;5103.311053007834;5116.846494605168;5124.615653825978;4699.295105734133;4877.317175595419;4957.628177886637;4960.454350021495;4963.3299504566785;5491.203775452577;5492.423295553989;5499.945000549983;5503.422140675251;5538.972305138489;4250.341197114421;4259.467566554181;4259.488861336659;4260.701750877409;4273.273543497613;5210.092430676992;5466.988607107698;5718.25662663851;5853.268341457318;5925.377835327315;4399.340098985113;4415.712978656377;4422.3366495026;4424.424824772759;4435.82256709728;4466.977665111654;4474.731516109044;4480.731156130642;4488.618467430305;4489.9551004490195;4827.637927155477;4900.558949694545;4906.656534042583;4907.386576677915;4925.38432695913;5517.668939863621;5545.417731138253;5549.694766787866;5549.805756798492;5564.165375193672;1530.9693806123814;1554.2306558253738;1558.3688606114542;1558.5947653610142;1562.671838913841;1081.5673730507704;1121.5906194239044;1130.3105105885404;1131.8302254661867;1148.2880614019361;4843.564079232886;4843.975780121077;4849.733264670477;4853.126437241331;4869.805207791649;5734.340550836624;5751.194832723422;5760.596758226968;5762.855928601734;5763.596548241583;4635.814567417333;4835.153848076572;4856.2958371036;4865.732384718804;4893.7063776173545;3005.549167624874;3015.351699384643;3018.501947178735;3021.8337991410244;3035.605371301717;5334.146536554133;5352.812651557177;5367.865803354947;5368.248445217672;5390.299261096033;5630.155476678531;5640.689762063043;5770.16332631766;5832.1834943108;5883.941160588425;5166.870828229325;5306.204069389622;5306.708131052675;5319.867003324948;5328.120860058047;5695.914561281667;5714.742836572447;5722.856928576819;5738.913916291175;5740.540756739534;5508.421615730291;5590.916136257878;5594.964931487688;5623.297087864017;5633.971830140823;5321.3348331458565;5324.281222035131;5336.919946200887;5346.732663366894;5349.170878513836;3931;3944.980275098606;3954.9209015820097;3961.4255932890146;3963.722539422213;5534.889302214014;5693.4021927696995;5722.370539240597;5728.198052272602;5745.454181852718;5509.283793107031;5527.143292789463;5551.361593416808;5558.749856256397;5583.190437386637;5415.187721841755;5510.752016159202;5526.972365138148;5535.806246781343;5546.4453554645825;5451.56387488992;5460.535854452416;5464.18037294409;5464.994253160825;5470.835874923759;5561.860953476195;5567.3040869891265;5568.860778480571;5581.023320918742;5592.916106258491;5265.394479634738;5368.597355198298;5392.137258038695;5420.349558053059;5428.972855135699 22:39:54 INFO - PID 29838 | |5;yui.html;726.2555880222774;726.5350175887431;736.7568702328232;739.4306384084256;747.9738209162681;12863.678408039803;13362.131461455;13377.7324453511;13422.32888355582;15827.366905323774;15249.618759531017;15259.77110343343;15540.378384864593;15563.610909727262;15577.610559735984;868.2142660891903;878.6968495868947;878.8286284174588;879.1472271896282;879.4415546128223;905.1175104273314;908.2506931781273;910.9089091090905;911.148076548426;911.6717981526646;932.9253659707207;944.8299306124894;944.9527523623825;944.9858252126223;945.754103932979;936.091990768953;939.5537119868043;940.4451373689487;941.1247539787994;941.256407438123;687.9989615110003;688.6453476459627;689.434663575868;689.441552342604;690.0959742736981;14099.577012689635;14164.36260368286;14552.199629020399;14605.78091328631;14636.780448293284;15263.084214947028;15297.770533442008;15298;15670.686586268212;15727.29227184768;12376.938115309365;12450.06624503166;12549.686257843536;12594.307313097775;12596.433160507799;2989.2676294308035;3008;3016.5625984232165;3017.351269477051;3017.396520695848;68075.65962170207;68943.65528172327;69525;70400.64799675968;71902.2809771906;9998.650047248311;10403.375797452178;10598.735031624272;12805.807912881128;12874;21167.470813229484;21229.893850530647;21476.57046859054;21548.78451215499;21572.892135539223;6260.655663938436;6290.716917738713;6294.559380843389;6300;6311.053341998645;2916.1834686287875;3018.728314451666;3025.107593259994;3036.5748795168693;3045.1625802904377;3488.127968007998;3590.605033446319;3598.2983318253214;3602.873899413508;3609.9458508122398;697.9487355185411;731.0532859946383;731.4075598764911;731.7877815433571;733.6845156582721;598.3866536799815;615.2555407956423;615.4430240632234;618.4866560754563;636;9868.21054315653;9960.651377201762;10096.899031009596;10394.272400931863;10638.627648032281;592.9407059294036;603.9094135879566;605.7243954000919;605.9424354686298;624.5971348480205;4444.400005999149;4506.639468842484;4508.887277818015;4513.277875539963;4532.138893610204;61957.84758743469;62595;63411.68294158592;63608;64193;55638.72255489022;58181;58312.41687583155;58817.70591147017;59286.70356648189;37453.62546374556;46627.533724663;48001.27998080102;50902.49097509051;51487.48512514827;9690.12788849007;9744.025597440199;10197.898021019695;10273;10366.740831479274;25304.74695253024;25667.871660641576;25950.480990380463;26215.606765898145;26493.60259596146;30120;30152;30376.69623303827;30697.846510767304;31022.84488577633;13687.657808554866;13912.304384780922;13928.721425571635;14013.719725605635;14165.150090994573;14867.479638213043;14915.477958271409;15513.612159696098;15671.529854104396;15850.841491584937;13238.801417978546;13240;13287.734245315232;13384.397702103233;13404.865951340362;13367.598972030855;13498.325083745576;13557.59327220185;13571.59285221445;13642.863571364556;12855.16441431304;13964.092333998258;13965.790513142108;13999.510017149758;14049.297535123407;9223.12380323895;9348.672796452092;10139;10180.490975451346;10306.33008854422;11202;11392.601258955896;11600.18798684084;11928;11996.220245684352 22:39:54 INFO - PID 29838 | __end_tp_report 22:39:54 INFO - PID 29838 | __start_cc_report 22:39:54 INFO - PID 29838 | _x_x_mozilla_cycle_collect,1198 22:39:54 INFO - PID 29838 | __end_cc_report 22:39:54 INFO - PID 29838 | __startTimestamp1494567594151__endTimestamp 22:39:54 INFO - PID 29838 | 22:39:54 INFO - PID 29838 | ------- Summary: start ------- 22:39:54 INFO - PID 29838 | Number of tests: 6 22:39:54 INFO - PID 29838 | 22:39:54 INFO - PID 29838 | [#0] dojo.html Cycles:165 Average:5056.01 Median:5235.74 stddev:614.51 (11.7%) stddev-sans-first:588.04 22:39:54 INFO - PID 29838 | Values: 2704.2 2711.7 2745.0 2751.9 2754.5 4932.8 5069.8 5159.5 5200.9 5226.9 5076.1 5111.2 5119.7 5164.1 5180.8 4211.4 4451.4 4554.6 4610.6 4624.0 4618.0 4628.7 4670.3 4689.1 4695.4 4477.7 4539.2 4540.0 4550.2 4594.3 4599.2 4609.9 4612.6 4633.7 4660.0 4542.4 4560.2 4600.4 4617.8 4632.7 5011.9 5079.6 5089.7 5161.7 5174.2 5505.2 5550.5 5585.0 5594.9 5605.8 5513.1 5532.9 5533.2 5535.5 5546.1 5042.1 5157.3 5160.5 5162.3 5216.8 5619.0 5671.3 5753.1 5756.2 5765.5 5360.4 5424.5 5426.2 5435.2 5438.0 5461.3 5515.2 5521.3 5554.8 5583.5 5328.6 5403.2 5406.3 5465.9 5505.2 5495.0 5495.5 5501.0 5502.0 5536.3 4270.0 4275.1 4278.1 4316.6 4325.7 3697.7 3722.3 3728.3 3736.0 3763.4 4746.7 4747.1 4749.2 4776.8 4797.8 5552.1 5592.8 5594.9 5605.3 5649.9 4606.7 4688.8 4698.5 4753.6 4759.3 4922.9 5018.3 5018.7 5024.1 5027.0 5028.7 5215.3 5230.1 5253.7 5255.5 5466.3 5502.1 5513.9 5529.4 5608.0 5173.9 5211.6 5218.2 5235.7 5249.8 5514.5 5519.8 5521.3 5521.9 5556.1 5445.8 5460.0 5482.3 5488.8 5499.8 5385.5 5388.2 5423.4 5448.4 5448.8 5432.9 5436.1 5441.5 5443.8 5494.7 5167.2 5270.2 5294.5 5367.7 5376.4 5483.2 5485.5 5489.6 5519.4 5533.1 5278.1 5327.7 5330.1 5333.5 5380.1 22:39:54 INFO - PID 29838 | 22:39:54 INFO - PID 29838 | [#1] ext.html Cycles:180 Average:28392.06 Median:3065.85 stddev:38042.78 (1240.9%) stddev-sans-first:38092.33 22:39:54 INFO - PID 29838 | Values: 617.5 646.8 670.8 999.1 1012.6 25477.7 32187.4 32714.8 32880.5 32938.0 34796.7 35039.5 35267.6 35276.0 35285.8 5215.5 5378.5 6859.6 7012.1 7015.2 6130.0 6882.4 6896.6 6900.6 6902.0 6905.7 7005.6 7008.2 7008.2 7013.2 6892.3 6924.6 6936.6 6945.4 6951.9 6782.5 6846.1 6869.1 6873.2 6875.8 30778.2 31037.8 31074.4 31131.1 31167.2 3011.9 3065.1 3065.3 3065.6 3066.1 23256.5 23316.7 23353.4 23361.0 23375.2 3091.6 3147.4 3148.7 3156.8 3162.3 136751.0 137271.0 137413.0 137631.0 137850.3 39666.2 39753.2 39769.0 39794.8 39804.6 41134.6 41502.6 41653.8 41658.8 41698.6 10883.3 10966.8 10969.9 10978.8 10980.8 8053.3 8179.3 8747.8 8752.6 8758.0 10965.2 10987.7 11006.4 11159.7 11239.3 4892.3 4900.7 5015.2 5032.7 5041.8 876.5 888.6 894.0 896.2 922.7 19573.8 20302.5 20444.2 20473.3 20854.4 947.3 958.7 982.5 985.5 989.8 10504.6 10504.7 10870.8 10871.9 11020.6 162139.0 162201.2 162741.2 162922.0 163020.0 37791.6 38039.0 38139.8 38141.0 38232.8 1156.3 1206.6 1216.1 1218.0 1220.9 18528.9 18951.5 19695.9 19880.9 20088.3 27646.0 27719.7 27745.7 27760.6 27791.0 140850.0 140913.0 140991.0 141213.0 141450.0 23442.3 23850.9 26503.3 26821.5 27375.6 30698.0 30811.1 31511.8 32192.5 32717.2 24807.4 25248.0 25358.2 25549.4 26801.7 24522.9 24903.0 25132.2 25456.2 26599.2 28036.3 28095.6 28156.6 28182.2 28194.7 24316.9 24665.6 24738.0 26645.6 27078.7 20950.2 21582.2 21867.7 21897.7 21947.8 22:39:54 INFO - PID 29838 | 22:39:54 INFO - PID 29838 | [#2] jquery.html Cycles:180 Average:23814.73 Median:38543.90 stddev:57739.14 (149.8%) stddev-sans-first:57874.36 22:39:54 INFO - PID 29838 | Values: 407.7 411.2 426.9 435.8 444.5 16206.2 17095.0 17630.2 17666.6 17695.1 16092.4 16134.4 16177.3 16283.0 17486.0 5361.5 5447.5 5462.7 5616.7 5622.1 5433.1 5511.6 5512.9 5539.1 5545.8 5277.7 5405.4 5507.8 5513.3 5520.0 5296.2 5418.6 5424.7 5428.5 5433.0 4781.4 4857.6 4912.8 4922.7 4977.1 19972.3 20577.9 20677.3 20833.9 20949.2 1622.4 1664.8 1677.1 1678.2 1680.7 13462.6 14147.2 14169.5 14221.4 14361.6 2033.3 2056.4 2088.1 2106.1 2107.3 80148.6 80623.0 80957.2 81545.0 82250.6 10250.3 10455.3 10655.9 10794.7 10822.4 15029.5 15052.1 15074.8 15077.1 15078.3 6649.2 6946.4 6969.6 7032.4 7078.3 6199.4 6203.5 6392.7 6395.5 6395.6 6557.6 6694.7 6713.0 6736.2 6881.3 1979.8 1988.8 2010.8 2019.9 2026.8 844.8 886.8 887.2 889.6 914.9 16169.4 16425.4 16429.4 16563.8 17144.6 869.4 871.4 875.1 879.0 896.9 8734.4 9125.1 9167.8 9338.7 9346.1 338699.0 345002.0 346113.0 348376.0 353428.0 76395.9 78467.6 78511.6 78691.6 81239.6 1044.3 1062.2 1102.7 1106.2 1107.3 16380.7 16717.7 16781.7 17151.1 17234.5 38460.8 38484.0 38603.8 39064.6 40220.8 46908.8 48007.8 48070.8 48343.3 48411.3 8065.0 8187.0 8258.8 8269.2 8494.0 11806.3 11873.4 12032.8 12237.5 12457.2 10764.8 10977.8 11180.8 11453.0 11453.1 9997.7 10147.3 10191.3 10285.9 10413.4 10795.9 10860.9 11095.0 11493.8 11541.2 7982.8 8105.6 8176.3 8264.7 8386.2 10710.7 10835.6 10992.4 11098.9 11220.7 22:39:54 INFO - PID 29838 | 22:39:54 INFO - PID 29838 | [#3] mootools.html Cycles:180 Average:6385.40 Median:3878.57 stddev:4081.74 (105.2%) stddev-sans-first:4073.97 22:39:54 INFO - PID 29838 | Values: 1114.0 1268.5 1307.3 1322.1 1328.5 8312.0 10190.4 10518.4 10527.6 10527.6 10262.0 10346.9 10361.8 10362.6 10368.7 1903.9 1973.0 1974.8 2214.9 2251.7 1960.8 2169.7 2280.0 2284.6 2300.0 2245.1 2251.4 2252.1 2256.6 2259.7 2086.6 2126.1 2128.8 2130.9 2132.1 2077.4 2118.6 2125.2 2126.9 2131.2 9907.6 10109.9 10135.1 10137.1 10142.3 5457.3 5638.3 5647.2 5651.0 5655.0 3365.7 3488.8 3563.1 3581.6 3599.4 1068.9 1087.0 1103.7 1162.6 1166.5 14899.6 14943.3 15064.4 15079.1 15084.2 4744.8 4855.0 5013.8 5077.0 5174.2 12568.1 12570.3 12574.8 12586.7 12587.9 5623.0 5713.3 5731.3 5732.6 5736.6 5082.3 5115.9 5135.7 5148.8 5161.7 4123.7 4146.1 4170.7 4192.6 4195.5 1338.6 1355.9 1368.3 1371.5 1374.3 680.4 703.6 703.8 720.0 722.4 5803.8 5905.3 5914.9 5978.2 6109.5 526.3 541.7 542.5 542.9 797.7 3778.3 3830.8 3854.4 3902.7 3996.1 12749.7 12772.2 13133.7 13289.1 13524.9 13043.3 13106.3 13118.7 13144.5 13316.0 7400.2 7514.8 7548.6 7590.4 7598.6 6141.6 6228.3 6273.9 6304.4 6346.9 11743.2 11869.5 11875.4 11959.4 11978.9 12063.6 12069.2 12122.8 12217.8 12378.4 7378.3 7567.7 8159.1 8291.2 8338.6 8259.8 9579.2 9679.6 9683.7 9694.7 7894.3 8520.3 8747.0 8924.2 9002.0 8199.3 8577.6 8621.5 8774.4 8801.2 7566.5 7637.0 7660.8 7674.8 7704.3 5384.8 5460.9 5476.2 5531.2 5594.6 5243.4 5351.4 5354.4 5425.2 5442.9 22:39:54 INFO - PID 29838 | 22:39:54 INFO - PID 29838 | [#4] prototype.html Cycles:180 Average:4644.29 Median:5140.23 stddev:1288.18 (25.1%) stddev-sans-first:1251.39 22:39:54 INFO - PID 29838 | Values: 379.1 385.5 390.3 390.5 393.0 5157.3 5179.9 5191.5 5196.2 5336.2 5113.1 5155.8 5167.2 5206.6 5226.6 3985.4 4044.7 4168.6 4188.2 4191.6 4173.6 4186.3 4186.3 4188.5 4189.8 4146.0 4148.3 4166.3 4188.1 4194.0 4055.9 4153.4 4179.9 4193.8 4194.5 3760.0 3788.8 3802.0 3804.3 3823.3 5075.0 5086.5 5103.3 5116.8 5124.6 4699.3 4877.3 4957.6 4960.5 4963.3 5491.2 5492.4 5499.9 5503.4 5539.0 4250.3 4259.5 4259.5 4260.7 4273.3 5210.1 5467.0 5718.3 5853.3 5925.4 4399.3 4415.7 4422.3 4424.4 4435.8 4467.0 4474.7 4480.7 4488.6 4490.0 4827.6 4900.6 4906.7 4907.4 4925.4 5517.7 5545.4 5549.7 5549.8 5564.2 1531.0 1554.2 1558.4 1558.6 1562.7 1081.6 1121.6 1130.3 1131.8 1148.3 4843.6 4844.0 4849.7 4853.1 4869.8 5734.3 5751.2 5760.6 5762.9 5763.6 4635.8 4835.2 4856.3 4865.7 4893.7 3005.5 3015.4 3018.5 3021.8 3035.6 5334.1 5352.8 5367.9 5368.2 5390.3 5630.2 5640.7 5770.2 5832.2 5883.9 5166.9 5306.2 5306.7 5319.9 5328.1 5695.9 5714.7 5722.9 5738.9 5740.5 5508.4 5590.9 5595.0 5623.3 5634.0 5321.3 5324.3 5336.9 5346.7 5349.2 3931.0 3945.0 3954.9 3961.4 3963.7 5534.9 5693.4 5722.4 5728.2 5745.5 5509.3 5527.1 5551.4 5558.7 5583.2 5415.2 5510.8 5527.0 5535.8 5546.4 5451.6 5460.5 5464.2 5465.0 5470.8 5561.9 5567.3 5568.9 5581.0 5592.9 5265.4 5368.6 5392.1 5420.3 5429.0 22:39:54 INFO - PID 29838 | 22:39:54 INFO - PID 29838 | [#5] yui.html Cycles:180 Average:14934.33 Median:16871.50 stddev:17656.36 (104.7%) stddev-sans-first:17673.65 22:39:54 INFO - PID 29838 | Values: 726.3 726.5 736.8 739.4 748.0 12863.7 13362.1 13377.7 13422.3 15827.4 15249.6 15259.8 15540.4 15563.6 15577.6 868.2 878.7 878.8 879.1 879.4 905.1 908.3 910.9 911.1 911.7 932.9 944.8 945.0 945.0 945.8 936.1 939.6 940.4 941.1 941.3 688.0 688.6 689.4 689.4 690.1 14099.6 14164.4 14552.2 14605.8 14636.8 15263.1 15297.8 15298.0 15670.7 15727.3 12376.9 12450.1 12549.7 12594.3 12596.4 2989.3 3008.0 3016.6 3017.4 3017.4 68075.7 68943.7 69525.0 70400.6 71902.3 9998.7 10403.4 10598.7 12805.8 12874.0 21167.5 21229.9 21476.6 21548.8 21572.9 6260.7 6290.7 6294.6 6300.0 6311.1 2916.2 3018.7 3025.1 3036.6 3045.2 3488.1 3590.6 3598.3 3602.9 3609.9 697.9 731.1 731.4 731.8 733.7 598.4 615.3 615.4 618.5 636.0 9868.2 9960.7 10096.9 10394.3 10638.6 592.9 603.9 605.7 605.9 624.6 4444.4 4506.6 4508.9 4513.3 4532.1 61957.8 62595.0 63411.7 63608.0 64193.0 55638.7 58181.0 58312.4 58817.7 59286.7 37453.6 46627.5 48001.3 50902.5 51487.5 9690.1 9744.0 10197.9 10273.0 10366.7 25304.7 25667.9 25950.5 26215.6 26493.6 30120.0 30152.0 30376.7 30697.8 31022.8 13687.7 13912.3 13928.7 14013.7 14165.2 14867.5 14915.5 15513.6 15671.5 15850.8 13238.8 13240.0 13287.7 13384.4 13404.9 13367.6 13498.3 13557.6 13571.6 13642.9 12855.2 13964.1 13965.8 13999.5 14049.3 9223.1 9348.7 10139.0 10180.5 10306.3 11202.0 11392.6 11600.2 11928.0 11996.2 22:39:54 INFO - PID 29838 | -------- Summary: end -------- 22:39:54 INFO - PID 29838 | 22:39:55 INFO - TEST-INFO | 29838: exit 0 22:39:55 INFO - TEST-OK | dromaeo_css | took 1121642ms 22:39:55 INFO - TEST-START | kraken 22:39:55 INFO - operating with platform_type : linux_ 22:39:55 INFO - Initialising browser for kraken test... 22:39:55 INFO - TEST-INFO | started process 30161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Njll5/profile http://localhost:41511/getInfo.html) 22:39:58 INFO - PID 30161 | __metrics Screen width/height:1600/1200 22:39:58 INFO - PID 30161 | colorDepth:24 22:39:58 INFO - PID 30161 | Browser inner width/height: 1024/695 22:39:58 INFO - PID 30161 | __metrics 22:39:59 INFO - PID 30161 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:39:59 INFO - PID 30161 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:40:00 INFO - TEST-INFO | 30161: exit 0 22:40:00 INFO - Browser initialized. 22:40:00 INFO - Running cycle 1/1 for kraken test... 22:40:00 INFO - TEST-INFO | started process 30256 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Njll5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:40:12 INFO - PID 30256 | RSS: Main: 149528576 22:40:12 INFO - PID 30256 | 22:40:15 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41511/tests/kraken/driver.html?testName=audio-beat-detection) 22:40:16 INFO - PID 30256 | RSS: Main: 221986816 22:40:16 INFO - PID 30256 | 22:40:19 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41511/tests/kraken/driver.html?testName=audio-dft) 22:40:19 INFO - PID 30256 | RSS: Main: 182501376 22:40:19 INFO - PID 30256 | 22:40:22 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41511/tests/kraken/driver.html?testName=audio-fft) 22:40:23 INFO - PID 30256 | RSS: Main: 264077312 22:40:23 INFO - PID 30256 | 22:40:25 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41511/tests/kraken/driver.html?testName=audio-oscillator) 22:40:26 INFO - PID 30256 | RSS: Main: 176115712 22:40:26 INFO - PID 30256 | 22:40:28 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41511/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:40:28 INFO - PID 30256 | RSS: Main: 177889280 22:40:28 INFO - PID 30256 | 22:40:32 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41511/tests/kraken/driver.html?testName=imaging-darkroom) 22:40:32 INFO - PID 30256 | RSS: Main: 238485504 22:40:32 INFO - PID 30256 | 22:40:36 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41511/tests/kraken/driver.html?testName=imaging-desaturate) 22:40:36 INFO - PID 30256 | RSS: Main: 238026752 22:40:36 INFO - PID 30256 | 22:40:39 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41511/tests/kraken/driver.html?testName=json-parse-financial) 22:40:40 INFO - PID 30256 | RSS: Main: 235470848 22:40:40 INFO - PID 30256 | 22:40:41 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41511/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:40:42 INFO - PID 30256 | RSS: Main: 182358016 22:40:42 INFO - PID 30256 | 22:40:44 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41511/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:40:44 INFO - PID 30256 | RSS: Main: 186826752 22:40:44 INFO - PID 30256 | 22:40:47 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41511/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:40:47 INFO - PID 30256 | RSS: Main: 214380544 22:40:47 INFO - PID 30256 | 22:40:50 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41511/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:40:51 INFO - PID 30256 | RSS: Main: 223678464 22:40:51 INFO - PID 30256 | 22:40:54 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41511/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:40:54 INFO - PID 30256 | RSS: Main: 220176384 22:40:54 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | Cycle 1(1): loaded http://localhost:41511/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41511/tests/kraken/driver.html?testName=ai-astar) 22:40:56 INFO - PID 30256 | RSS: Main: 269869056 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | __start_tp_report 22:40:56 INFO - PID 30256 | _x_x_mozilla_page_load 22:40:56 INFO - PID 30256 | _x_x_mozilla_page_load_details 22:40:56 INFO - PID 30256 | |i|pagename|runs| 22:40:56 INFO - PID 30256 | |0;ai-astar;110;113;112;112;114;111;112;113;112;112 22:40:56 INFO - PID 30256 | |1;audio-beat-detection;144;122;121;194;123;129;122;127;147;129 22:40:56 INFO - PID 30256 | |2;audio-dft;163;164;163;164;163;164;162;160;163;163 22:40:56 INFO - PID 30256 | |3;audio-fft;75;76;75;102;77;79;75;77;102;75 22:40:56 INFO - PID 30256 | |4;audio-oscillator;84;83;103;84;85;84;98;84;84;102 22:40:56 INFO - PID 30256 | |5;imaging-gaussian-blur;114;113;113;113;113;113;113;113;113;114 22:40:56 INFO - PID 30256 | |6;imaging-darkroom;149;149;149;150;149;149;150;150;148;149 22:40:56 INFO - PID 30256 | |7;imaging-desaturate;95;94;94;94;94;94;94;94;93;94 22:40:56 INFO - PID 30256 | |8;json-parse-financial;53;53;55;54;53;55;55;54;54;55 22:40:56 INFO - PID 30256 | |9;json-stringify-tinderbox;56;55;56;56;56;56;56;56;56;56 22:40:56 INFO - PID 30256 | |10;stanford-crypto-aes;82;81;81;80;82;80;81;81;80;81 22:40:56 INFO - PID 30256 | |11;stanford-crypto-ccm;133;140;131;130;129;130;133;131;193;129 22:40:56 INFO - PID 30256 | |12;stanford-crypto-pbkdf2;187;188;186;189;188;189;193;189;188;190 22:40:56 INFO - PID 30256 | |13;stanford-crypto-sha256-iterative;76;73;74;73;72;73;72;71;71;76 22:40:56 INFO - PID 30256 | __end_tp_report 22:40:56 INFO - PID 30256 | __start_cc_report 22:40:56 INFO - PID 30256 | _x_x_mozilla_cycle_collect,2011 22:40:56 INFO - PID 30256 | __end_cc_report 22:40:56 INFO - PID 30256 | __startTimestamp1494567656946__endTimestamp 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | ------- Summary: start ------- 22:40:56 INFO - PID 30256 | Number of tests: 14 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:1.10 (1.0%) stddev-sans-first:0.87 22:40:56 INFO - PID 30256 | Values: 110.0 113.0 112.0 112.0 114.0 111.0 112.0 113.0 112.0 112.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:129.00 stddev:22.39 (17.4%) stddev-sans-first:23.56 22:40:56 INFO - PID 30256 | Values: 144.0 122.0 121.0 194.0 123.0 129.0 122.0 127.0 147.0 129.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:1.20 (0.7%) stddev-sans-first:1.27 22:40:56 INFO - PID 30256 | Values: 163.0 164.0 163.0 164.0 163.0 164.0 162.0 160.0 163.0 163.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.50 stddev:10.99 (14.6%) stddev-sans-first:11.41 22:40:56 INFO - PID 30256 | Values: 75.0 76.0 75.0 102.0 77.0 79.0 75.0 77.0 102.0 75.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:84.00 stddev:8.32 (9.9%) stddev-sans-first:8.62 22:40:56 INFO - PID 30256 | Values: 84.0 83.0 103.0 84.0 85.0 84.0 98.0 84.0 84.0 102.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 22:40:56 INFO - PID 30256 | Values: 114.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 22:40:56 INFO - PID 30256 | Values: 149.0 149.0 149.0 150.0 149.0 149.0 150.0 150.0 148.0 149.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 22:40:56 INFO - PID 30256 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:0.88 (1.6%) stddev-sans-first:0.83 22:40:56 INFO - PID 30256 | Values: 53.0 53.0 55.0 54.0 53.0 55.0 55.0 54.0 54.0 55.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#9] json-stringify-tinderbox Cycles:10 Average:55.90 Median:56.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 22:40:56 INFO - PID 30256 | Values: 56.0 55.0 56.0 56.0 56.0 56.0 56.0 56.0 56.0 56.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:0.74 (0.9%) stddev-sans-first:0.67 22:40:56 INFO - PID 30256 | Values: 82.0 81.0 81.0 80.0 82.0 80.0 81.0 81.0 80.0 81.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#11] stanford-crypto-ccm Cycles:10 Average:137.90 Median:132.00 stddev:19.63 (14.9%) stddev-sans-first:20.74 22:40:56 INFO - PID 30256 | Values: 133.0 140.0 131.0 130.0 129.0 130.0 133.0 131.0 193.0 129.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.70 Median:189.00 stddev:1.89 (1.0%) stddev-sans-first:1.90 22:40:56 INFO - PID 30256 | Values: 187.0 188.0 186.0 189.0 188.0 189.0 193.0 189.0 188.0 190.0 22:40:56 INFO - PID 30256 | 22:40:56 INFO - PID 30256 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.00 stddev:1.79 (2.5%) stddev-sans-first:1.56 22:40:56 INFO - PID 30256 | Values: 76.0 73.0 74.0 73.0 72.0 73.0 72.0 71.0 71.0 76.0 22:40:56 INFO - PID 30256 | -------- Summary: end -------- 22:40:56 INFO - PID 30256 | 22:40:58 INFO - TEST-INFO | 30256: exit 0 22:40:58 INFO - TEST-OK | kraken | took 63114ms 22:40:58 INFO - SUITE-END | took 1184s 22:40:58 INFO - Completed test suite (00:19:45) 22:40:58 INFO - javascript benchmark 22:40:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6588.378922583557, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2704.1617098699403, 2711.7017128115917, 2744.958825617616, 2751.876165572549, 2754.476649436608, 4932.778024988876, 5069.822556210534, 5159.484051594844, 5200.912719915286, 5226.895462090756, 5076.111680455915, 5111.1566591512355, 5119.692818430897, 5164.096283150452, 5180.792768289273, 4211.368294755781, 4451.376807246988, 4554.567316104964, 4610.631149508048, 4624.005838744669, 4618.007128467379, 4628.675992680497, 4670.252759558472, 4689.062187562484, 4695.366125573055, 4477.731336119843, 4539.16025535277, 4540.0, 4550.203714349976, 4594.333821595883, 4599.195140850338, 4609.884752881171, 4612.607928326062, 4633.698809577367, 4660.0, 4542.38677778499, 4560.179167749804, 4600.378948841931, 4617.815287388534, 4632.698874573176, 5011.874703132415, 5079.593632509391, 5089.6691715038405, 5161.664491808058, 5174.172132458789, 5505.174223866412, 5550.47270509301, 5585.022621041301, 5594.860128496779, 5605.831825045255, 5513.09034009388, 5532.944670553283, 5533.170024496318, 5535.501804837584, 5546.084895992157, 5042.092423363756, 5157.329547158845, 5160.52472813761, 5162.328897243372, 5216.7913283469, 5619.0, 5671.291088613923, 5753.108268218432, 5756.1653560233535, 5765.509931655773, 5360.41035486104, 5424.538914192259, 5426.186072089139, 5435.211894275308, 5438.0, 5461.317335333083, 5515.227868098469, 5521.282233309725, 5554.750036248379, 5583.525400340935, 5328.573714102862, 5403.189521571796, 5406.297181366397, 5465.9180112298345, 5505.229267902497, 5495.0383682855345, 5495.450454954473, 5501.037318469252, 5501.972490137524, 5536.307961504812, 4269.978650106792, 4275.102228532043, 4278.101598664253, 4316.568343165658, 4325.740455572675, 3697.685696715809, 3722.2555488902326, 3728.2729867676085, 3736.0473079365133, 3763.397856342972, 4746.738929358849, 4747.0505898820375, 4749.192637251606, 4776.808927642855, 4797.832075877397, 5552.11166213412, 5592.832215033555, 5594.888102238014, 5605.299337582802, 5649.887002259932, 4606.723596584216, 4688.789004494806, 4698.483166851644, 4753.5959443447, 4759.309900064472, 4922.90154196914, 5018.322526458955, 5018.673786203885, 5024.070546948753, 5027.044861476381, 5028.723420211777, 5215.322008138917, 5230.084735171254, 5253.658512196695, 5255.500727430888, 5466.34403871538, 5502.064649009598, 5513.862153446035, 5529.4470552944385, 5608.018596745472, 5173.922391164211, 5211.557017653542, 5218.217267409919, 5235.738213089254, 5249.842504724865, 5514.531264842373, 5519.806806761744, 5521.337439507285, 5521.944780552143, 5556.083246264321, 5445.809396671098, 5459.972700136474, 5482.259894914136, 5488.780448782004, 5499.752511137089, 5385.461453854583, 5388.164834450667, 5423.4034256231, 5448.427915069019, 5448.83653490396, 5432.945670543402, 5436.0758671026815, 5441.455854414527, 5443.836684899459, 5494.670319780826, 5167.22491626259, 5270.156774916149, 5294.549963253013, 5367.704776237344, 5376.435474275145, 5483.204935284281, 5485.451454854483, 5489.615726899079, 5519.420460851553, 5533.142362933751, 5278.129108697022, 5327.706976116351, 5330.067238233371, 5333.493318134771, 5380.139177731544], "value": 5010.611585824317, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.4720613875137, 646.7671638210244, 670.758526930305, 999.1058003087237, 1012.5848402155118, 25477.74522254777, 32187.356252874928, 32714.836425817837, 32880.506792398075, 32938.01185964422, 34796.65203347966, 35039.4744078839, 35267.647323526755, 35276.0, 35285.823570882174, 5215.530602245797, 5378.489043540857, 6859.622720750369, 7012.088428504288, 7015.158181018285, 6130.0, 6882.449404047664, 6896.620685862275, 6900.6204658743745, 6901.965490172543, 6905.654717264142, 7005.614691191982, 7008.159020917497, 7008.194057683355, 7013.193482749472, 6892.310768923093, 6924.619145946971, 6936.618485983269, 6945.4096401805655, 6951.89572156418, 6782.491313151533, 6846.075779769717, 6869.141357330333, 6873.243943166248, 6875.759348422831, 30778.23054423635, 31037.844810776027, 31074.378512429852, 31131.066068017997, 31167.22081947947, 3011.9397612047856, 3065.111117775842, 3065.340951695396, 3065.6167979002626, 3066.080175947207, 23256.53486930269, 23316.65025024626, 23353.416164595852, 23361.0, 23375.181868634518, 3091.5980922480157, 3147.3862596793406, 3148.685131486856, 3156.794808337451, 3162.2884850908363, 136751.0, 137271.0, 137413.0, 137631.0, 137850.31074844662, 39666.20667586632, 39753.20493590112, 39769.0, 39794.80102599468, 39804.601953980666, 41134.58865411367, 41502.58497415048, 41653.791731041754, 41658.791706041884, 41698.58301417008, 10883.346999180074, 10966.780664386828, 10969.890301097046, 10978.835317470244, 10980.835287470694, 8053.31546818527, 8179.263866252066, 8747.781305467413, 8752.56237188138, 8757.956210219036, 10965.232433729721, 10987.670369888756, 11006.394648294421, 11159.72100697489, 11239.269447485887, 4892.290617860391, 4900.730459824744, 5015.1975683890405, 5032.7483625819295, 5041.8487445376695, 876.4960147914975, 888.6223355074197, 893.9865902011475, 896.2158111652304, 922.718570835888, 19573.804261957197, 20302.492437689176, 20444.182232710824, 20473.283435079702, 20854.37436876896, 947.2564037230878, 958.736347504428, 982.4547376206132, 985.4629227071264, 989.7970915962211, 10504.63233786829, 10504.684859454228, 10870.782584348268, 10871.945640271748, 11020.614278500214, 162139.0, 162201.18899405663, 162741.18629407015, 162922.0, 163020.0, 37791.62208377936, 38039.0, 38139.80930095332, 38141.0, 38232.80883595564, 1156.3351073132983, 1206.6018213989362, 1216.087934049463, 1217.9634610961684, 1220.8962238209851, 18528.879381273353, 18951.526211844815, 19695.901520492305, 19880.90059549693, 20088.296909608678, 27646.032388866293, 27719.722802771714, 27745.722542775122, 27760.583591245744, 27791.0, 140850.0, 140913.0, 140991.0, 141213.0, 141450.0, 23442.296731098093, 23850.88074559616, 26503.33741656397, 26821.463570728865, 27375.589366159922, 30698.0, 30811.075667730005, 31511.842440788565, 32192.517112243804, 32717.18207044843, 24807.379815504755, 25248.017112556096, 25358.239252822445, 25549.361265967756, 26801.731982680703, 24522.87738561296, 24903.0, 25132.246032619052, 25456.23631291064, 26599.202023939313, 28036.299092522033, 28095.578566321114, 28156.577651334836, 28182.15453536315, 28194.718052819208, 24316.878415607807, 24665.63001555014, 24738.0, 26645.600315995664, 27078.72921270762, 20950.161993520087, 21582.244621438174, 21867.67198491992, 21897.67153492667, 21947.780522194575], "value": 13714.569527550202, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.694229328004, 411.15917947796754, 426.9487661480622, 435.8343829344849, 444.5110378583556, 16206.189690515486, 17095.0, 17630.206640701166, 17666.558336041606, 17695.115244237786, 16092.436764713237, 16134.435294764686, 16177.272022758974, 16283.0, 17486.0, 5361.498780341511, 5447.45525447455, 5462.672239665632, 5616.747246373902, 5622.072358060915, 5433.1035379162395, 5511.559075273988, 5512.944870551303, 5539.141433077859, 5545.805896793593, 5277.736113194344, 5405.351357837065, 5507.834764957038, 5513.255710479074, 5519.972400137994, 5296.232046353276, 5418.593605479605, 5424.674519528821, 5428.511433970942, 5433.049216387156, 4781.3545171401765, 4857.562819346241, 4912.778924948386, 4922.729249891255, 4977.054359671687, 19972.300969466, 20577.897110514503, 20677.27629532959, 20833.89583052075, 20949.16203351864, 1622.4078211452734, 1664.8168701442833, 1677.1111311005186, 1678.2196278730448, 1680.7058764716126, 13462.596122116352, 14147.221902795342, 14169.504067357593, 14221.431142754278, 14361.640958976004, 2033.2680235115347, 2056.3728062940795, 2088.1021160900805, 2106.147010460766, 2107.3045894854663, 80148.59925700392, 80623.0, 80957.19042809615, 81544.9999999994, 82250.58874705648, 10250.333728307636, 10455.320404173857, 10655.946720266504, 10794.73013174677, 10822.404767737693, 15029.473968411272, 15052.118485189352, 15074.773878391832, 15077.095374277578, 15078.321475533627, 6649.202095748541, 6946.37482626566, 6969.6166710830375, 7032.402245809163, 7078.256783037707, 6199.380061993855, 6203.503719702413, 6392.7123279452535, 6395.488360931115, 6395.648239346788, 6557.573757705733, 6694.665266736643, 6712.96643516789, 6736.157980252468, 6881.346272104142, 1979.7921218272163, 1988.8110629490175, 2010.8290795282273, 2019.9394018179185, 2026.7669218040114, 844.8394804987033, 886.778305423644, 887.1527691055027, 889.6263569300779, 914.9496777677158, 16169.35322587107, 16425.425110121087, 16429.424970126227, 16563.83436165647, 17144.571385715455, 869.3523325122824, 871.4466313890725, 875.1161327059716, 878.9868151977726, 896.8565029595234, 8734.388592798443, 9125.133112354448, 9167.816643667089, 9338.673146439842, 9346.11211934865, 338699.0, 345002.0, 346113.0, 348376.0, 353428.0, 76395.85406218912, 78467.60766196133, 78511.60744196242, 78691.60654196692, 81239.5938020306, 1044.289882879634, 1062.1768129699544, 1102.6691992402248, 1106.181425744943, 1107.302399488301, 16380.672386551963, 16717.74923376126, 16781.748273775658, 17151.142442878056, 17234.482965511055, 38460.80769596134, 38484.0, 38603.80698096604, 39064.6093539061, 40220.79889600533, 46908.76545617387, 48007.759961201366, 48070.75964620154, 48343.27485087797, 48411.27383089186, 8065.0, 8187.0, 8258.793530161794, 8269.21442462965, 8494.0, 11806.291622502677, 11873.406329683308, 12032.75934481323, 12237.510499580274, 12457.1902826316, 10764.838527422251, 10977.780444390908, 11180.832287515532, 11453.0, 11453.141014423785, 9997.700068997941, 10147.34042287249, 10191.337563058378, 10285.897141028494, 10413.42726150069, 10795.892041079804, 10860.891391085988, 11095.001449869227, 11493.770124597628, 11541.249818761753, 7982.760517184493, 8105.594720263845, 8176.264136227768, 8264.710735124241, 8386.245237928615, 10710.678679639623, 10835.566577336818, 10992.44568934948, 11098.944505277745, 11220.66338009861], "value": 8633.468465542863, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1113.9944300278498, 1268.479923231475, 1307.3136603283276, 1322.067942100819, 1328.5283724277874, 8311.958440207798, 10190.439525826077, 10518.368897866114, 10527.578896844136, 10527.63153289635, 10262.0, 10346.896531034687, 10361.792764144731, 10362.585496580128, 10368.688939331812, 1903.9333623323187, 1972.9802701973013, 1974.82226599606, 2214.944626384341, 2251.684764133023, 1960.7745109312468, 2169.6853956176346, 2279.9658005129936, 2284.6001949658744, 2300.0454811253308, 2245.113180293783, 2251.3583628665833, 2252.1329288223988, 2256.5825322315422, 2259.6836442898007, 2086.5722526882028, 2126.1176611706123, 2128.776478469754, 2130.8508404411696, 2132.1471411435396, 2077.4079387374677, 2118.555103428282, 2125.234915430444, 2126.904289306985, 2131.232756207764, 9907.554160062815, 10109.949450252776, 10135.138513226384, 10137.138343240833, 10142.290039697224, 5457.345118585756, 5638.2670252866865, 5647.152927060933, 5651.0, 5654.971725141348, 3365.6802603752603, 3488.7557870949045, 3563.1092226943265, 3581.6239294874185, 3599.3881040223223, 1068.8931106889327, 1087.0, 1103.7130346110068, 1162.5698491558178, 1166.4575972172895, 14899.627509312353, 14943.252837358197, 15064.397424103023, 15079.095254284777, 15084.17037062961, 4744.833930812405, 4854.988887183873, 5013.849584512471, 5076.974615126901, 5174.223866420067, 12568.057395695176, 12570.308633025088, 12574.81137782934, 12586.68533286657, 12587.93706031464, 5623.015972204929, 5713.314402271754, 5731.340895797037, 5732.627379220337, 5736.627119237236, 5082.313887625197, 5115.8721031973755, 5135.717535535507, 5148.819791307286, 5161.66449180802, 4123.6912966977325, 4146.129312844276, 4170.709498290854, 4192.601702838226, 4195.517515485758, 1338.5783478204335, 1355.9322033898266, 1368.2679766325168, 1371.5131128449457, 1374.3471850870956, 680.425040840489, 703.6235613946515, 703.781827633425, 719.9928000720031, 722.4148439763808, 5803.82588522345, 5905.291365036223, 5914.8817023659285, 5978.163057172, 6109.450149486479, 526.2500936165948, 541.7453796715538, 542.5496837624762, 542.940276569577, 797.6849144587972, 3778.263238668433, 3830.80845957701, 3854.402567602026, 3902.746321489094, 3996.0609256824623, 12749.681257968625, 12772.233665980071, 13133.737325253633, 13289.069765116348, 13524.864751352558, 13043.28261945602, 13106.279154646396, 13118.672033199056, 13144.539941102015, 13316.001299902353, 7400.222976587488, 7514.849703005909, 7548.584827834522, 7590.354819840265, 7598.620068996639, 6141.631502109888, 6228.252609686685, 6273.905891411541, 6304.432601065926, 6346.904796427965, 11743.177977541833, 11869.525218991144, 11875.406229688308, 11959.402029898645, 11978.940105299418, 12063.577774777841, 12069.189621556836, 12122.818157727464, 12217.755644887231, 12378.44297006655, 7378.29906158914, 7567.697292108254, 8159.143289954469, 8291.212334828182, 8338.624761885609, 8259.793505162419, 9579.185769209416, 9679.564419601276, 9683.66107186245, 9694.660686875926, 7894.328982036362, 8520.3183745299, 8747.0, 8924.152205540464, 9002.0, 8199.303059239794, 8577.614007369559, 8621.482711037357, 8774.429662071945, 8801.207891289558, 7566.470347075652, 7637.0, 7660.846783064419, 7674.769756907079, 7704.26809453101, 5384.811531596375, 5460.890782184255, 5476.151196564539, 5531.170324451204, 5594.552435805044, 5243.370795504564, 5351.438098999549, 5354.411014788292, 5425.213344065168, 5442.863928401822], "value": 4782.649887821904, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.06560328789527, 385.54120596490156, 390.2877249020538, 390.5040598439981, 393.04490089083527, 5157.277981082647, 5179.948200517993, 5191.506806853347, 5196.168613021912, 5336.17289320155, 5113.130767769479, 5155.845324640258, 5167.199084141964, 5206.5574426173725, 5226.588821018332, 3985.3822657488063, 4044.7370920890194, 4168.62482376586, 4188.183304255671, 4191.622753952143, 4173.582641735832, 4186.267403204442, 4186.28833098374, 4188.518320393148, 4189.7695626740515, 4145.979270103645, 4148.278464437255, 4166.333386658135, 4188.141431006637, 4194.0, 4055.858044968412, 4153.418521407006, 4179.916401671965, 4193.791749311794, 4194.454720886296, 3760.0, 3788.7726736395903, 3801.961980380216, 3804.258169656918, 3823.3118038753014, 5074.980386669962, 5086.542211200973, 5103.311053007834, 5116.846494605168, 5124.615653825978, 4699.295105734133, 4877.317175595419, 4957.628177886637, 4960.454350021495, 4963.3299504566785, 5491.203775452577, 5492.423295553989, 5499.945000549983, 5503.422140675251, 5538.972305138489, 4250.341197114421, 4259.467566554181, 4259.488861336659, 4260.701750877409, 4273.273543497613, 5210.092430676992, 5466.988607107698, 5718.25662663851, 5853.268341457318, 5925.377835327315, 4399.340098985113, 4415.712978656377, 4422.3366495026, 4424.424824772759, 4435.82256709728, 4466.977665111654, 4474.731516109044, 4480.731156130642, 4488.618467430305, 4489.9551004490195, 4827.637927155477, 4900.558949694545, 4906.656534042583, 4907.386576677915, 4925.38432695913, 5517.668939863621, 5545.417731138253, 5549.694766787866, 5549.805756798492, 5564.165375193672, 1530.9693806123814, 1554.2306558253738, 1558.3688606114542, 1558.5947653610142, 1562.671838913841, 1081.5673730507704, 1121.5906194239044, 1130.3105105885404, 1131.8302254661867, 1148.2880614019361, 4843.564079232886, 4843.975780121077, 4849.733264670477, 4853.126437241331, 4869.805207791649, 5734.340550836624, 5751.194832723422, 5760.596758226968, 5762.855928601734, 5763.596548241583, 4635.814567417333, 4835.153848076572, 4856.2958371036, 4865.732384718804, 4893.7063776173545, 3005.549167624874, 3015.351699384643, 3018.501947178735, 3021.8337991410244, 3035.605371301717, 5334.146536554133, 5352.812651557177, 5367.865803354947, 5368.248445217672, 5390.299261096033, 5630.155476678531, 5640.689762063043, 5770.16332631766, 5832.1834943108, 5883.941160588425, 5166.870828229325, 5306.204069389622, 5306.708131052675, 5319.867003324948, 5328.120860058047, 5695.914561281667, 5714.742836572447, 5722.856928576819, 5738.913916291175, 5740.540756739534, 5508.421615730291, 5590.916136257878, 5594.964931487688, 5623.297087864017, 5633.971830140823, 5321.3348331458565, 5324.281222035131, 5336.919946200887, 5346.732663366894, 5349.170878513836, 3931.0, 3944.980275098606, 3954.9209015820097, 3961.4255932890146, 3963.722539422213, 5534.889302214014, 5693.4021927696995, 5722.370539240597, 5728.198052272602, 5745.454181852718, 5509.283793107031, 5527.143292789463, 5551.361593416808, 5558.749856256397, 5583.190437386637, 5415.187721841755, 5510.752016159202, 5526.972365138148, 5535.806246781343, 5546.4453554645825, 5451.56387488992, 5460.535854452416, 5464.18037294409, 5464.994253160825, 5470.835874923759, 5561.860953476195, 5567.3040869891265, 5568.860778480571, 5581.023320918742, 5592.916106258491, 5265.394479634738, 5368.597355198298, 5392.137258038695, 5420.349558053059, 5428.972855135699], "value": 4262.456040359013, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.2555880222774, 726.5350175887431, 736.7568702328232, 739.4306384084256, 747.9738209162681, 12863.678408039803, 13362.131461455, 13377.7324453511, 13422.32888355582, 15827.366905323774, 15249.618759531017, 15259.77110343343, 15540.378384864593, 15563.610909727262, 15577.610559735984, 868.2142660891903, 878.6968495868947, 878.8286284174588, 879.1472271896282, 879.4415546128223, 905.1175104273314, 908.2506931781273, 910.9089091090905, 911.148076548426, 911.6717981526646, 932.9253659707207, 944.8299306124894, 944.9527523623825, 944.9858252126223, 945.754103932979, 936.091990768953, 939.5537119868043, 940.4451373689487, 941.1247539787994, 941.256407438123, 687.9989615110003, 688.6453476459627, 689.434663575868, 689.441552342604, 690.0959742736981, 14099.577012689635, 14164.36260368286, 14552.199629020399, 14605.78091328631, 14636.780448293284, 15263.084214947028, 15297.770533442008, 15298.0, 15670.686586268212, 15727.29227184768, 12376.938115309365, 12450.06624503166, 12549.686257843536, 12594.307313097775, 12596.433160507799, 2989.2676294308035, 3008.0, 3016.5625984232165, 3017.351269477051, 3017.396520695848, 68075.65962170207, 68943.65528172327, 69525.0, 70400.64799675968, 71902.2809771906, 9998.650047248311, 10403.375797452178, 10598.735031624272, 12805.807912881128, 12874.0, 21167.470813229484, 21229.893850530647, 21476.57046859054, 21548.78451215499, 21572.892135539223, 6260.655663938436, 6290.716917738713, 6294.559380843389, 6300.0, 6311.053341998645, 2916.1834686287875, 3018.728314451666, 3025.107593259994, 3036.5748795168693, 3045.1625802904377, 3488.127968007998, 3590.605033446319, 3598.2983318253214, 3602.873899413508, 3609.9458508122398, 697.9487355185411, 731.0532859946383, 731.4075598764911, 731.7877815433571, 733.6845156582721, 598.3866536799815, 615.2555407956423, 615.4430240632234, 618.4866560754563, 636.0, 9868.21054315653, 9960.651377201762, 10096.899031009596, 10394.272400931863, 10638.627648032281, 592.9407059294036, 603.9094135879566, 605.7243954000919, 605.9424354686298, 624.5971348480205, 4444.400005999149, 4506.639468842484, 4508.887277818015, 4513.277875539963, 4532.138893610204, 61957.84758743469, 62595.0, 63411.68294158592, 63608.0, 64193.0, 55638.72255489022, 58181.0, 58312.41687583155, 58817.70591147017, 59286.70356648189, 37453.62546374556, 46627.533724663, 48001.27998080102, 50902.49097509051, 51487.48512514827, 9690.12788849007, 9744.025597440199, 10197.898021019695, 10273.0, 10366.740831479274, 25304.74695253024, 25667.871660641576, 25950.480990380463, 26215.606765898145, 26493.60259596146, 30120.0, 30152.0, 30376.69623303827, 30697.846510767304, 31022.84488577633, 13687.657808554866, 13912.304384780922, 13928.721425571635, 14013.719725605635, 14165.150090994573, 14867.479638213043, 14915.477958271409, 15513.612159696098, 15671.529854104396, 15850.841491584937, 13238.801417978546, 13240.0, 13287.734245315232, 13384.397702103233, 13404.865951340362, 13367.598972030855, 13498.325083745576, 13557.59327220185, 13571.59285221445, 13642.863571364556, 12855.16441431304, 13964.092333998258, 13965.790513142108, 13999.510017149758, 14049.297535123407, 9223.12380323895, 9348.672796452092, 10139.0, 10180.490975451346, 10306.33008854422, 11202.0, 11392.601258955896, 11600.18798684084, 11928.0, 11996.220245684352], "value": 6761.680349643881, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 112.0, 112.0, 114.0, 111.0, 112.0, 113.0, 112.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 122.0, 121.0, 194.0, 123.0, 129.0, 122.0, 127.0, 147.0, 129.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 164.0, 163.0, 164.0, 162.0, 160.0, 163.0, 163.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 102.0, 77.0, 79.0, 75.0, 77.0, 102.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 103.0, 84.0, 85.0, 84.0, 98.0, 84.0, 84.0, 102.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 150.0, 149.0, 149.0, 150.0, 150.0, 148.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 54.0, 53.0, 55.0, 55.0, 54.0, 54.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 80.0, 82.0, 80.0, 81.0, 81.0, 80.0, 81.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 140.0, 131.0, 130.0, 129.0, 130.0, 133.0, 131.0, 193.0, 129.0], "value": 137.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 186.0, 189.0, 188.0, 189.0, 193.0, 189.0, 188.0, 190.0], "value": 188.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 74.0, 73.0, 72.0, 73.0, 72.0, 71.0, 71.0, 76.0], "value": 73.1, "unit": "score"}]}]} 22:40:58 INFO - Return code: 0 22:40:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:40:58 INFO - # TBPL SUCCESS # 22:40:58 INFO - Running post-action listener: _resource_record_post_action 22:40:58 INFO - [mozharness: 2017-05-12 05:40:58.721885Z] Finished run-tests step (success) 22:40:58 INFO - Running post-run listener: _resource_record_post_run 22:40:58 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 365858816; Read time: 0; Write time: 321500 22:40:58 INFO - TinderboxPrint: CPU usage
12.9% 22:40:58 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 22:40:58 INFO - TinderboxPrint: I/O write bytes / time
365,858,816 / 321,500 22:40:58 INFO - TinderboxPrint: CPU idle
8,347.1 (87.1%) 22:40:58 INFO - TinderboxPrint: CPU user
1,195.1 (12.5%) 22:40:58 INFO - TinderboxPrint: Swap in / out
0 / 0 22:40:58 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 22822912; Read time: 0; Write time: 3856 22:40:58 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 255025152; Read time: 0; Write time: 181136 22:40:59 INFO - Running post-run listener: _upload_blobber_files 22:40:59 INFO - Blob upload gear active. 22:40:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:40:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:40:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:40:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:40:59 INFO - (blobuploader) - INFO - Open directory for files ... 22:40:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:40:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:41:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:00 INFO - (blobuploader) - INFO - Done attempting. 22:41:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:41:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:41:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:01 INFO - (blobuploader) - INFO - Done attempting. 22:41:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:41:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:41:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:02 INFO - (blobuploader) - INFO - Done attempting. 22:41:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:41:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:41:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:05 INFO - (blobuploader) - INFO - Done attempting. 22:41:05 INFO - (blobuploader) - INFO - Iteration through files over. 22:41:05 INFO - Return code: 0 22:41:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:41:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:41:05 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/971dede2fcb25441551744aac025a23ae03db1e7faa9f85d8fd14641b1b7548ace63b196c713e8829ef8a95e2fa9a0793b3652761fb3eba096a59b4876a111d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f58a79c3e57abdaea308b750802f5d1260c113bc58f62e2789d5a2d43feedfc344b23303e856795cc671a48a7587e55cf5a46b9dc575b57fb211443b742f846f", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af7ee23d9f993e2cb6bea1124f6233c219304249f6520e18cc767bfe456a9058819930baba4c37c43db8652a4364431d1fb9289b9a7e9b15ba4ddd0cbd4e90dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b01b4788d6b8bae3db3b27164d474572080163310221ce2878a63270371676e3d821b41d7a0cd7322dae0f4fbb15b951fa1f65bcfcc0e6292b0a16fc7352342c"} 22:41:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:41:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:41:05 INFO - Contents: 22:41:05 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/971dede2fcb25441551744aac025a23ae03db1e7faa9f85d8fd14641b1b7548ace63b196c713e8829ef8a95e2fa9a0793b3652761fb3eba096a59b4876a111d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f58a79c3e57abdaea308b750802f5d1260c113bc58f62e2789d5a2d43feedfc344b23303e856795cc671a48a7587e55cf5a46b9dc575b57fb211443b742f846f", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af7ee23d9f993e2cb6bea1124f6233c219304249f6520e18cc767bfe456a9058819930baba4c37c43db8652a4364431d1fb9289b9a7e9b15ba4ddd0cbd4e90dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b01b4788d6b8bae3db3b27164d474572080163310221ce2878a63270371676e3d821b41d7a0cd7322dae0f4fbb15b951fa1f65bcfcc0e6292b0a16fc7352342c"} 22:41:05 INFO - Running post-run listener: copy_logs_to_upload_dir 22:41:05 INFO - Copying logs to upload dir... 22:41:05 INFO - mkdir: /builds/slave/test/build/upload/logs 22:41:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.639006 ========= master_lag: 0.81 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-05-11 22:41:05.853778) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 22:41:05.857464) ========= 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=14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/971dede2fcb25441551744aac025a23ae03db1e7faa9f85d8fd14641b1b7548ace63b196c713e8829ef8a95e2fa9a0793b3652761fb3eba096a59b4876a111d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f58a79c3e57abdaea308b750802f5d1260c113bc58f62e2789d5a2d43feedfc344b23303e856795cc671a48a7587e55cf5a46b9dc575b57fb211443b742f846f", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af7ee23d9f993e2cb6bea1124f6233c219304249f6520e18cc767bfe456a9058819930baba4c37c43db8652a4364431d1fb9289b9a7e9b15ba4ddd0cbd4e90dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b01b4788d6b8bae3db3b27164d474572080163310221ce2878a63270371676e3d821b41d7a0cd7322dae0f4fbb15b951fa1f65bcfcc0e6292b0a16fc7352342c"} build_url:https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/IDGks8c5QO6QxzWqVkabFQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/971dede2fcb25441551744aac025a23ae03db1e7faa9f85d8fd14641b1b7548ace63b196c713e8829ef8a95e2fa9a0793b3652761fb3eba096a59b4876a111d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f58a79c3e57abdaea308b750802f5d1260c113bc58f62e2789d5a2d43feedfc344b23303e856795cc671a48a7587e55cf5a46b9dc575b57fb211443b742f846f", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af7ee23d9f993e2cb6bea1124f6233c219304249f6520e18cc767bfe456a9058819930baba4c37c43db8652a4364431d1fb9289b9a7e9b15ba4ddd0cbd4e90dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b01b4788d6b8bae3db3b27164d474572080163310221ce2878a63270371676e3d821b41d7a0cd7322dae0f4fbb15b951fa1f65bcfcc0e6292b0a16fc7352342c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 22:41:05.886928) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 22:41:05.887264) ========= 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=14ef55db36f7623abb31b9cf000001d5-1494566409.766203-1443815126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004526 ========= master_lag: 1.25 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 22:41:07.139581) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 22:41:07.145536) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 22:41:07.148582) ========= ========= Total master_lag: 2.14 =========