builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-002 starttime: 1490735049.84 results: success (0) revision: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:09.844413) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:09.844852) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:09.845128) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:09.861530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:09.862114) ========= 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=a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-28 14:04: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% 50.5M=0s 2017-03-28 14:04:10 (50.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121053 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:10.029881) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:10.030396) ========= 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=a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020603 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:10.080037) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 14:04:10.080376) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 272ce6c2572164f5f6a9fba2a980ba9ccf50770c --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 272ce6c2572164f5f6a9fba2a980ba9ccf50770c --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=a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-28 14:04:10,367 truncating revision to first 12 chars 2017-03-28 14:04:10,367 Setting DEBUG logging. 2017-03-28 14:04:10,367 attempt 1/10 2017-03-28 14:04:10,368 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/272ce6c25721?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 14:04:11,167 unpacking tar archive at: mozilla-central-272ce6c25721/testing/mozharness/ program finished with exit code 0 elapsedTime=1.115242 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 14:04:11.212995) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:11.213492) ========= script_repo_revision: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:11.213886) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:11.214150) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:04:11.226107) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-03-28 14:04:11.226721) ========= /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=a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:04:11 INFO - MultiFileLogger online at 20170328 14:04:11 in /builds/slave/test 14:04: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 14:04:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:04:11 INFO - {'append_to_log': False, 14:04:11 INFO - 'base_work_dir': '/builds/slave/test', 14:04:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:04:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:04:11 INFO - 'branch': 'Firefox-Non-PGO', 14:04:11 INFO - 'buildbot_json_path': 'buildprops.json', 14:04:11 INFO - 'config_files': ('talos/linux_config.py',), 14:04:11 INFO - 'default_actions': ('clobber', 14:04:11 INFO - 'read-buildbot-config', 14:04:11 INFO - 'download-and-extract', 14:04:11 INFO - 'populate-webroot', 14:04:11 INFO - 'create-virtualenv', 14:04:11 INFO - 'install', 14:04:11 INFO - 'run-tests'), 14:04:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:04:11 INFO - 'download_minidump_stackwalk': True, 14:04:11 INFO - 'download_symbols': 'ondemand', 14:04:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:04:11 INFO - 'tooltool.py': '/tools/tooltool.py', 14:04:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:04:11 INFO - '/tools/misc-python/virtualenv.py')}, 14:04:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:04:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:04:11 INFO - 'gecko_profile': False, 14:04:11 INFO - 'gecko_profile_interval': 0, 14:04:11 INFO - 'installer_path': 'installer.exe', 14:04:11 INFO - 'log_level': 'info', 14:04:11 INFO - 'log_name': 'talos', 14:04:11 INFO - 'log_to_console': True, 14:04:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:04:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:04:11 INFO - 'opt_config_files': (), 14:04:11 INFO - 'pip_index': False, 14:04:11 INFO - 'suite': 'dromaeojs', 14:04:11 INFO - 'system_bits': '32', 14:04:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:04:11 INFO - 'title': 'talos-linux64-ix-002', 14:04:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:04:11 INFO - 'use_talos_json': True, 14:04:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:04:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:04:11 INFO - 'work_dir': 'build'} 14:04:11 INFO - [mozharness: 2017-03-28 21:04:11.601912Z] Running clobber step. 14:04:11 INFO - Running pre-action listener: _resource_record_pre_action 14:04:11 INFO - Running main action method: clobber 14:04:11 INFO - rmtree: /builds/slave/test/build 14:04:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:04:13 INFO - Running post-action listener: _resource_record_post_action 14:04:13 INFO - [mozharness: 2017-03-28 21:04:13.059783Z] Finished clobber step (success) 14:04:13 INFO - [mozharness: 2017-03-28 21:04:13.059880Z] Running read-buildbot-config step. 14:04:13 INFO - Running pre-action listener: _resource_record_pre_action 14:04:13 INFO - Running main action method: read_buildbot_config 14:04:13 INFO - Using buildbot properties: 14:04:13 INFO - { 14:04:13 INFO - "project": "", 14:04:13 INFO - "product": "firefox", 14:04:13 INFO - "who": "kwierso@gmail.com", 14:04:13 INFO - "installer_path": "public/build/target.tar.bz2", 14:04:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 14:04:13 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:04:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 14:04:13 INFO - "stage_platform": "linux64", 14:04:13 INFO - "basedir": "/builds/slave/test", 14:04:13 INFO - "buildnumber": 904, 14:04:13 INFO - "platform": "ubuntu64_hw", 14:04:13 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:04:13 INFO - "slavebuilddir": "test", 14:04:13 INFO - "taskId": "bn81NRwVROWthssHEX5K_w", 14:04:13 INFO - "branch": "mozilla-central", 14:04:13 INFO - "script_repo_revision": "production", 14:04:13 INFO - "revision": "272ce6c2572164f5f6a9fba2a980ba9ccf50770c", 14:04:13 INFO - "slavename": "talos-linux64-ix-002", 14:04:13 INFO - "repo_path": "mozilla-central" 14:04:13 INFO - } 14:04:13 INFO - Finding installer, test and symbols from parent task. 14:04:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bn81NRwVROWthssHEX5K_w'}, attempt #1 14:04:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bn81NRwVROWthssHEX5K_w'}, attempt #1 14:04:14 INFO - Task dependencies: E4-tXzFeS-KdUQc8v92AhQ 14:04:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ'}, attempt #1 14:04:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.tar.bz2 14:04:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/test_packages.json 14:04:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.crashreporter-symbols.zip 14:04:14 INFO - Running post-action listener: _resource_record_post_action 14:04:14 INFO - [mozharness: 2017-03-28 21:04:14.491166Z] Finished read-buildbot-config step (success) 14:04:14 INFO - [mozharness: 2017-03-28 21:04:14.491380Z] Running download-and-extract step. 14:04:14 INFO - Running pre-action listener: _resource_record_pre_action 14:04:14 INFO - Running main action method: download_and_extract 14:04:14 INFO - mkdir: /builds/slave/test/build/tests 14:04:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:14 INFO - trying https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.test_packages.json 14:04:14 INFO - Downloading https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:04:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:04:15 INFO - Downloaded 986 bytes. 14:04:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:04:15 INFO - Using the following test package requirements: 14:04:15 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:04:15 INFO - u'common': [u'target.common.tests.zip'], 14:04:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:04:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:04:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:04:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:04:15 INFO - u'mozbase': [u'target.common.tests.zip'], 14:04:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:04:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:04:15 INFO - u'web-platform': [u'target.common.tests.zip', 14:04:15 INFO - u'target.web-platform.tests.zip'], 14:04:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:04:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:04:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.common.tests.zip 14:04:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:04:15 INFO - Fetch https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.common.tests.zip into memory 14:04:16 INFO - Expected file size: 58223110 14:04:16 INFO - Obtained file size: 58223110 14:04:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:04:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.common.tests.zip 14:04:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:04:25 INFO - Fetch https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.common.tests.zip into memory 14:04:26 INFO - Expected file size: 58223110 14:04:26 INFO - Obtained file size: 58223110 14:04:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.talos.tests.zip 14:04:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:04:37 INFO - Fetch https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.talos.tests.zip into memory 14:04:38 INFO - Expected file size: 14404171 14:04:38 INFO - Obtained file size: 14404171 14:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:38 INFO - trying https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.tar.bz2 14:04:38 INFO - Downloading https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:04:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:04:39 INFO - Downloaded 62539017 bytes. 14:04:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.tar.bz2 14:04:39 INFO - mkdir: /builds/slave/test/properties 14:04:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:04:39 INFO - Writing to file /builds/slave/test/properties/build_url 14:04:39 INFO - Contents: 14:04:39 INFO - build_url:https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.tar.bz2 14:04:39 INFO - Running post-action listener: _resource_record_post_action 14:04:39 INFO - Running post-action listener: set_extra_try_arguments 14:04:39 INFO - [mozharness: 2017-03-28 21:04:39.721373Z] Finished download-and-extract step (success) 14:04:39 INFO - [mozharness: 2017-03-28 21:04:39.721469Z] Running populate-webroot step. 14:04:39 INFO - Running pre-action listener: _resource_record_pre_action 14:04:39 INFO - Running main action method: populate_webroot 14:04:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:04:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:04:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:04:39 INFO - '--tppagecycles', 14:04:39 INFO - '7'], 14:04:39 INFO - 'tests': ['tsvgm']}}, 14:04:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:04:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:04:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:04:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:04:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:04:39 INFO - 'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:04:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:04:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:04:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:04:39 INFO - 'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['damp', 'tps']}, 14:04:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:04:39 INFO - 'tests': ['damp', 'tps']}, 14:04:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['dromaeo_dom']}, 14:04:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:04:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['basic_compositor_video']}, 14:04:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:04:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['a11yr', 14:04:39 INFO - 'ts_paint', 14:04:39 INFO - 'tpaint', 14:04:39 INFO - 'sessionrestore', 14:04:39 INFO - 'sessionrestore_no_auto_restore', 14:04:39 INFO - 'tabpaint']}, 14:04:39 INFO - 'other-e10s': {'tests': ['a11yr', 14:04:39 INFO - 'ts_paint', 14:04:39 INFO - 'tpaint', 14:04:39 INFO - 'sessionrestore', 14:04:39 INFO - 'sessionrestore_no_auto_restore', 14:04:39 INFO - 'tabpaint']}, 14:04:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:04:39 INFO - 'ts_paint', 14:04:39 INFO - 'tpaint', 14:04:39 INFO - 'sessionrestore', 14:04:39 INFO - 'sessionrestore_no_auto_restore', 14:04:39 INFO - 'tabpaint']}, 14:04:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:04:39 INFO - 'ts_paint', 14:04:39 INFO - 'tpaint', 14:04:39 INFO - 'sessionrestore', 14:04:39 INFO - 'sessionrestore_no_auto_restore', 14:04:39 INFO - 'tabpaint']}, 14:04:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['a11yr', 14:04:39 INFO - 'ts_paint', 14:04:39 INFO - 'tpaint', 14:04:39 INFO - 'sessionrestore', 14:04:39 INFO - 'sessionrestore_no_auto_restore', 14:04:39 INFO - 'tabpaint']}, 14:04:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['a11yr', 14:04:39 INFO - 'ts_paint', 14:04:39 INFO - 'tpaint', 14:04:39 INFO - 'sessionrestore', 14:04:39 INFO - 'sessionrestore_no_auto_restore', 14:04:39 INFO - 'tabpaint']}, 14:04:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['tsvgx', 14:04:39 INFO - 'tsvgr_opacity', 14:04:39 INFO - 'tart', 14:04:39 INFO - 'tscrollx', 14:04:39 INFO - 'cart', 14:04:39 INFO - 'tsvg_static']}, 14:04:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:04:39 INFO - 'tsvgr_opacity', 14:04:39 INFO - 'tart', 14:04:39 INFO - 'tscrollx', 14:04:39 INFO - 'cart', 14:04:39 INFO - 'tsvg_static']}, 14:04:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:04:39 INFO - 'talos_options': ['--disable-e10s'], 14:04:39 INFO - 'tests': ['tp5o']}, 14:04:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:04:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:04:39 INFO - 'talos_options': ['--disable-e10s', 14:04:39 INFO - '--xperf_path', 14:04:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:04:39 INFO - 'tests': ['tp5n']}, 14:04:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:04:39 INFO - 'talos_options': ['--xperf_path', 14:04:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:04:39 INFO - 'tests': ['tp5n']}}, 14:04:39 INFO - 'talos.zip': {'path': '', 14:04:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:04:39 INFO - Running post-action listener: _resource_record_post_action 14:04:39 INFO - [mozharness: 2017-03-28 21:04:39.734496Z] Finished populate-webroot step (success) 14:04:39 INFO - [mozharness: 2017-03-28 21:04:39.734584Z] Running create-virtualenv step. 14:04:39 INFO - Running pre-action listener: _resource_record_pre_action 14:04:39 INFO - Running main action method: create_virtualenv 14:04:39 INFO - Creating virtualenv /builds/slave/test/build/venv 14:04: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 14:04:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:04:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:04:39 INFO - Using real prefix '/usr' 14:04:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:04:40 INFO - Installing distribute.............................................................................................................................................................................................done. 14:04:42 INFO - Installing pip.................done. 14:04:42 INFO - Return code: 0 14:04:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:04:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:04:42 INFO - Reading from file tmpfile_stdout 14:04:42 INFO - Output received: 14:04: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) 14:04:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:04:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:04:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:04:42 INFO - Using env: {'DISPLAY': ':0', 14:04:42 INFO - 'HOME': '/home/cltbld', 14:04:42 INFO - 'LANG': 'en_US.UTF-8', 14:04:42 INFO - 'LANGUAGE': 'en_US:en', 14:04:42 INFO - 'LOGNAME': 'cltbld', 14:04:42 INFO - 'MAIL': '/var/mail/cltbld', 14:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:04:42 INFO - 'MOZ_NO_REMOTE': '1', 14:04:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:04:42 INFO - 'NO_EM_RESTART': '1', 14:04:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:04:42 INFO - 'PWD': '/builds/slave/test', 14:04:42 INFO - 'SHELL': '/bin/bash', 14:04:42 INFO - 'SHLVL': '1', 14:04:42 INFO - 'TERM': 'linux', 14:04:42 INFO - 'TMOUT': '86400', 14:04:42 INFO - 'USER': 'cltbld', 14:04:42 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:04:42 INFO - '_': '/tools/buildbot/bin/python'} 14:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:04: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 14:04:42 INFO - Cleaning up... 14:04:42 INFO - Return code: 0 14:04:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:04:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:04:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:04:42 INFO - Using env: {'DISPLAY': ':0', 14:04:42 INFO - 'HOME': '/home/cltbld', 14:04:42 INFO - 'LANG': 'en_US.UTF-8', 14:04:42 INFO - 'LANGUAGE': 'en_US:en', 14:04:42 INFO - 'LOGNAME': 'cltbld', 14:04:42 INFO - 'MAIL': '/var/mail/cltbld', 14:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:04:42 INFO - 'MOZ_NO_REMOTE': '1', 14:04:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:04:42 INFO - 'NO_EM_RESTART': '1', 14:04:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:04:42 INFO - 'PWD': '/builds/slave/test', 14:04:42 INFO - 'SHELL': '/bin/bash', 14:04:42 INFO - 'SHLVL': '1', 14:04:42 INFO - 'TERM': 'linux', 14:04:42 INFO - 'TMOUT': '86400', 14:04:42 INFO - 'USER': 'cltbld', 14:04:42 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:04:42 INFO - '_': '/tools/buildbot/bin/python'} 14:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:04:42 INFO - Downloading/unpacking psutil>=3.1.1 14:04: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 14:04: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 14:04:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04: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 14:04: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 14:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:04:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:04:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:04:45 INFO - Installing collected packages: psutil 14:04:45 INFO - Running setup.py install for psutil 14:04:45 INFO - building 'psutil._psutil_linux' extension 14:04:45 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 14:04:45 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 14:04:45 INFO - building 'psutil._psutil_posix' extension 14:04:45 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 14:04:45 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 14:04:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:04:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:04:45 INFO - Successfully installed psutil 14:04:45 INFO - Cleaning up... 14:04:45 INFO - Return code: 0 14:04:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:04:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:04:45 INFO - Using env: {'DISPLAY': ':0', 14:04:45 INFO - 'HOME': '/home/cltbld', 14:04:45 INFO - 'LANG': 'en_US.UTF-8', 14:04:45 INFO - 'LANGUAGE': 'en_US:en', 14:04:45 INFO - 'LOGNAME': 'cltbld', 14:04:45 INFO - 'MAIL': '/var/mail/cltbld', 14:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:04:45 INFO - 'MOZ_NO_REMOTE': '1', 14:04:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:04:45 INFO - 'NO_EM_RESTART': '1', 14:04:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:04:45 INFO - 'PWD': '/builds/slave/test', 14:04:45 INFO - 'SHELL': '/bin/bash', 14:04:45 INFO - 'SHLVL': '1', 14:04:45 INFO - 'TERM': 'linux', 14:04:45 INFO - 'TMOUT': '86400', 14:04:45 INFO - 'USER': 'cltbld', 14:04:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:04:45 INFO - '_': '/tools/buildbot/bin/python'} 14:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:04:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:04: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 14:04: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 14:04:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04: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 14:04: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 14:04:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:04:48 INFO - Installing collected packages: mozsystemmonitor 14:04:48 INFO - Running setup.py install for mozsystemmonitor 14:04:48 INFO - Successfully installed mozsystemmonitor 14:04:48 INFO - Cleaning up... 14:04:48 INFO - Return code: 0 14:04:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:04:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:04:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:04:48 INFO - Using env: {'DISPLAY': ':0', 14:04:48 INFO - 'HOME': '/home/cltbld', 14:04:48 INFO - 'LANG': 'en_US.UTF-8', 14:04:48 INFO - 'LANGUAGE': 'en_US:en', 14:04:48 INFO - 'LOGNAME': 'cltbld', 14:04:48 INFO - 'MAIL': '/var/mail/cltbld', 14:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:04:48 INFO - 'MOZ_NO_REMOTE': '1', 14:04:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:04:48 INFO - 'NO_EM_RESTART': '1', 14:04:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:04:48 INFO - 'PWD': '/builds/slave/test', 14:04:48 INFO - 'SHELL': '/bin/bash', 14:04:48 INFO - 'SHLVL': '1', 14:04:48 INFO - 'TERM': 'linux', 14:04:48 INFO - 'TMOUT': '86400', 14:04:48 INFO - 'USER': 'cltbld', 14:04:48 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:04:48 INFO - '_': '/tools/buildbot/bin/python'} 14:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:04:48 INFO - Downloading/unpacking jsonschema==2.5.1 14:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:04:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:04:50 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 14:04:50 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 14:04:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:50 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 14:04:50 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 14:04:50 INFO - Downloading functools32-3.2.3-2.tar.gz 14:04:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:04:50 INFO - warning: no files found matching '*.txt' 14:04:50 INFO - no previously-included directories found matching 'build' 14:04:50 INFO - no previously-included directories found matching 'dist' 14:04:50 INFO - no previously-included directories found matching '.git*' 14:04:50 INFO - Installing collected packages: jsonschema, functools32 14:04:50 INFO - Running setup.py install for functools32 14:04:50 INFO - warning: no files found matching '*.txt' 14:04:50 INFO - no previously-included directories found matching 'build' 14:04:50 INFO - no previously-included directories found matching 'dist' 14:04:50 INFO - no previously-included directories found matching '.git*' 14:04:50 INFO - Successfully installed jsonschema functools32 14:04:50 INFO - Cleaning up... 14:04:50 INFO - Return code: 0 14:04:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:04:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:04:50 INFO - Using env: {'DISPLAY': ':0', 14:04:50 INFO - 'HOME': '/home/cltbld', 14:04:50 INFO - 'LANG': 'en_US.UTF-8', 14:04:50 INFO - 'LANGUAGE': 'en_US:en', 14:04:50 INFO - 'LOGNAME': 'cltbld', 14:04:50 INFO - 'MAIL': '/var/mail/cltbld', 14:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:04:50 INFO - 'MOZ_NO_REMOTE': '1', 14:04:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:04:50 INFO - 'NO_EM_RESTART': '1', 14:04:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:04:50 INFO - 'PWD': '/builds/slave/test', 14:04:50 INFO - 'SHELL': '/bin/bash', 14:04:50 INFO - 'SHLVL': '1', 14:04:50 INFO - 'TERM': 'linux', 14:04:50 INFO - 'TMOUT': '86400', 14:04:50 INFO - 'USER': 'cltbld', 14:04:50 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:04:50 INFO - '_': '/tools/buildbot/bin/python'} 14:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:04:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:04:51 INFO - Cleaning up... 14:04:51 INFO - Return code: 0 14:04:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:04:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:04:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:04:51 INFO - Using env: {'DISPLAY': ':0', 14:04:51 INFO - 'HOME': '/home/cltbld', 14:04:51 INFO - 'LANG': 'en_US.UTF-8', 14:04:51 INFO - 'LANGUAGE': 'en_US:en', 14:04:51 INFO - 'LOGNAME': 'cltbld', 14:04:51 INFO - 'MAIL': '/var/mail/cltbld', 14:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:04:51 INFO - 'MOZ_NO_REMOTE': '1', 14:04:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:04:51 INFO - 'NO_EM_RESTART': '1', 14:04:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:04:51 INFO - 'PWD': '/builds/slave/test', 14:04:51 INFO - 'SHELL': '/bin/bash', 14:04:51 INFO - 'SHLVL': '1', 14:04:51 INFO - 'TERM': 'linux', 14:04:51 INFO - 'TMOUT': '86400', 14:04:51 INFO - 'USER': 'cltbld', 14:04:51 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:04:51 INFO - '_': '/tools/buildbot/bin/python'} 14:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:04:51 INFO - Downloading/unpacking blobuploader==1.2.4 14:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:53 INFO - Downloading blobuploader-1.2.4.tar.gz 14:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:04:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:04:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:54 INFO - Downloading docopt-0.6.1.tar.gz 14:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:04:54 INFO - Installing collected packages: blobuploader, requests, docopt 14:04:54 INFO - Running setup.py install for blobuploader 14:04:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:04:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:04:54 INFO - Running setup.py install for requests 14:04:54 INFO - Running setup.py install for docopt 14:04:54 INFO - Successfully installed blobuploader requests docopt 14:04:54 INFO - Cleaning up... 14:04:54 INFO - Return code: 0 14:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:04:54 INFO - Using env: {'DISPLAY': ':0', 14:04:54 INFO - 'HOME': '/home/cltbld', 14:04:54 INFO - 'LANG': 'en_US.UTF-8', 14:04:54 INFO - 'LANGUAGE': 'en_US:en', 14:04:54 INFO - 'LOGNAME': 'cltbld', 14:04:54 INFO - 'MAIL': '/var/mail/cltbld', 14:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:04:54 INFO - 'MOZ_NO_REMOTE': '1', 14:04:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:04:54 INFO - 'NO_EM_RESTART': '1', 14:04:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:04:54 INFO - 'PWD': '/builds/slave/test', 14:04:54 INFO - 'SHELL': '/bin/bash', 14:04:54 INFO - 'SHLVL': '1', 14:04:54 INFO - 'TERM': 'linux', 14:04:54 INFO - 'TMOUT': '86400', 14:04:54 INFO - 'USER': 'cltbld', 14:04:54 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:04:54 INFO - '_': '/tools/buildbot/bin/python'} 14:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:04:55 INFO - Running setup.py (path:/tmp/pip-SqL_aQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:04:55 INFO - Running setup.py (path:/tmp/pip-qXQkmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:04:55 INFO - Running setup.py (path:/tmp/pip-XrNgYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:04:55 INFO - Running setup.py (path:/tmp/pip-VVXkDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:04:55 INFO - Running setup.py (path:/tmp/pip-abQvPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:04:55 INFO - Running setup.py (path:/tmp/pip-7TduYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:04:55 INFO - Running setup.py (path:/tmp/pip-SNVe3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:04:55 INFO - Running setup.py (path:/tmp/pip-aHLajd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:04:55 INFO - Running setup.py (path:/tmp/pip-0okRJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:04:55 INFO - Running setup.py (path:/tmp/pip-jaQo7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:04:55 INFO - Running setup.py (path:/tmp/pip-eUk5LT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:04:55 INFO - Running setup.py (path:/tmp/pip-pgJnK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:04:55 INFO - Running setup.py (path:/tmp/pip-5C_djS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:04:55 INFO - Running setup.py (path:/tmp/pip-SxpH_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:04:56 INFO - Running setup.py (path:/tmp/pip-Rky56B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:04:56 INFO - Running setup.py (path:/tmp/pip-xpac3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:04:56 INFO - Running setup.py (path:/tmp/pip-8gVhUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:04:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:04:56 INFO - Running setup.py install for manifestparser 14:04:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:04:56 INFO - Running setup.py install for mozcrash 14:04:56 INFO - Running setup.py install for mozdebug 14:04:56 INFO - Running setup.py install for mozdevice 14:04:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:04:56 INFO - Running setup.py install for mozfile 14:04:56 INFO - Running setup.py install for mozhttpd 14:04:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:04:56 INFO - Running setup.py install for mozinfo 14:04:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:04:56 INFO - Running setup.py install for mozInstall 14:04:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Running setup.py install for mozleak 14:04:57 INFO - Running setup.py install for mozlog 14:04:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Running setup.py install for moznetwork 14:04:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Running setup.py install for mozprocess 14:04:57 INFO - Running setup.py install for mozprofile 14:04:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Running setup.py install for mozrunner 14:04:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:04:57 INFO - Running setup.py install for mozscreenshot 14:04:57 INFO - Running setup.py install for moztest 14:04:57 INFO - Running setup.py install for mozversion 14:04:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:04:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:04:58 INFO - Cleaning up... 14:04:58 INFO - Return code: 0 14:04:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:04:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:04:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:04:58 INFO - Using env: {'DISPLAY': ':0', 14:04:58 INFO - 'HOME': '/home/cltbld', 14:04:58 INFO - 'LANG': 'en_US.UTF-8', 14:04:58 INFO - 'LANGUAGE': 'en_US:en', 14:04:58 INFO - 'LOGNAME': 'cltbld', 14:04:58 INFO - 'MAIL': '/var/mail/cltbld', 14:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:04:58 INFO - 'MOZ_NO_REMOTE': '1', 14:04:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:04:58 INFO - 'NO_EM_RESTART': '1', 14:04:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:04:58 INFO - 'PWD': '/builds/slave/test', 14:04:58 INFO - 'SHELL': '/bin/bash', 14:04:58 INFO - 'SHLVL': '1', 14:04:58 INFO - 'TERM': 'linux', 14:04:58 INFO - 'TMOUT': '86400', 14:04:58 INFO - 'USER': 'cltbld', 14:04:58 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:04:58 INFO - '_': '/tools/buildbot/bin/python'} 14:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:04:58 INFO - Running setup.py (path:/tmp/pip-7jVhAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:04:58 INFO - Running setup.py (path:/tmp/pip-GU3fzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:04:58 INFO - Running setup.py (path:/tmp/pip-uJI6md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:04:58 INFO - Running setup.py (path:/tmp/pip-f8BzH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:04:58 INFO - Running setup.py (path:/tmp/pip-olgGHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:04:58 INFO - Running setup.py (path:/tmp/pip-MzqYKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:04:58 INFO - Running setup.py (path:/tmp/pip-s0nTaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:04:58 INFO - Running setup.py (path:/tmp/pip-9WY5Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:04:58 INFO - Running setup.py (path:/tmp/pip-LkPtFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:04:58 INFO - Running setup.py (path:/tmp/pip-i6krXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:04:58 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)) 14:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:04:58 INFO - Running setup.py (path:/tmp/pip-35uuIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:04:59 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)) 14:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:04:59 INFO - Running setup.py (path:/tmp/pip-3dwyIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:04:59 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)) 14:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:04:59 INFO - Running setup.py (path:/tmp/pip-NXKUYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:04:59 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)) 14:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:04:59 INFO - Running setup.py (path:/tmp/pip-Hz2HKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:04:59 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)) 14:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:04:59 INFO - Running setup.py (path:/tmp/pip-p9qX4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:04:59 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)) 14:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:04:59 INFO - Running setup.py (path:/tmp/pip-U_4uHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:04:59 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)) 14:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:04:59 INFO - Running setup.py (path:/tmp/pip-71MAhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:04:59 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)) 14:04:59 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)) 14:04:59 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)) 14:04:59 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)) 14:04:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:04:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:04:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:04:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:01 INFO - Downloading blessings-1.6.tar.gz 14:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:05:01 INFO - Installing collected packages: blessings 14:05:01 INFO - Running setup.py install for blessings 14:05:01 INFO - Successfully installed blessings 14:05:01 INFO - Cleaning up... 14:05:01 INFO - Return code: 0 14:05:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:05:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:05:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:05:01 INFO - Reading from file tmpfile_stdout 14:05:01 INFO - Current package versions: 14:05:01 INFO - argparse == 1.2.1 14:05:01 INFO - blessings == 1.6 14:05:01 INFO - blobuploader == 1.2.4 14:05:01 INFO - docopt == 0.6.1 14:05:01 INFO - functools32 == 3.2.3-2 14:05:01 INFO - jsonschema == 2.5.1 14:05:01 INFO - manifestparser == 1.1 14:05:01 INFO - mozInstall == 1.12 14:05:01 INFO - mozcrash == 1.0 14:05:01 INFO - mozdebug == 0.1 14:05:01 INFO - mozdevice == 0.49 14:05:01 INFO - mozfile == 1.2 14:05:01 INFO - mozhttpd == 0.7 14:05:01 INFO - mozinfo == 0.9 14:05:01 INFO - mozleak == 0.1 14:05:01 INFO - mozlog == 3.4 14:05:01 INFO - moznetwork == 0.27 14:05:01 INFO - mozprocess == 0.25 14:05:01 INFO - mozprofile == 0.28 14:05:01 INFO - mozrunner == 6.13 14:05:01 INFO - mozscreenshot == 0.1 14:05:01 INFO - mozsystemmonitor == 0.3 14:05:01 INFO - moztest == 0.8 14:05:01 INFO - mozversion == 1.4 14:05:01 INFO - psutil == 3.1.1 14:05:01 INFO - requests == 1.2.3 14:05:01 INFO - wsgiref == 0.1.2 14:05:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:05:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:05:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:05:01 INFO - Using env: {'DISPLAY': ':0', 14:05:01 INFO - 'HOME': '/home/cltbld', 14:05:01 INFO - 'LANG': 'en_US.UTF-8', 14:05:01 INFO - 'LANGUAGE': 'en_US:en', 14:05:01 INFO - 'LOGNAME': 'cltbld', 14:05:01 INFO - 'MAIL': '/var/mail/cltbld', 14:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:01 INFO - 'MOZ_NO_REMOTE': '1', 14:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:01 INFO - 'NO_EM_RESTART': '1', 14:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:01 INFO - 'PWD': '/builds/slave/test', 14:05:01 INFO - 'SHELL': '/bin/bash', 14:05:01 INFO - 'SHLVL': '1', 14:05:01 INFO - 'TERM': 'linux', 14:05:01 INFO - 'TMOUT': '86400', 14:05:01 INFO - 'USER': 'cltbld', 14:05:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:01 INFO - '_': '/tools/buildbot/bin/python'} 14:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 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)) 14:05:02 INFO - Cleaning up... 14:05:02 INFO - Return code: 0 14:05:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15af1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, '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': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', '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 14:05:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:05:02 INFO - Using env: {'DISPLAY': ':0', 14:05:02 INFO - 'HOME': '/home/cltbld', 14:05:02 INFO - 'LANG': 'en_US.UTF-8', 14:05:02 INFO - 'LANGUAGE': 'en_US:en', 14:05:02 INFO - 'LOGNAME': 'cltbld', 14:05:02 INFO - 'MAIL': '/var/mail/cltbld', 14:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:02 INFO - 'MOZ_NO_REMOTE': '1', 14:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:02 INFO - 'NO_EM_RESTART': '1', 14:05:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:02 INFO - 'PWD': '/builds/slave/test', 14:05:02 INFO - 'SHELL': '/bin/bash', 14:05:02 INFO - 'SHLVL': '1', 14:05:02 INFO - 'TERM': 'linux', 14:05:02 INFO - 'TMOUT': '86400', 14:05:02 INFO - 'USER': 'cltbld', 14:05:02 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:02 INFO - '_': '/tools/buildbot/bin/python'} 14:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:05:02 INFO - Cleaning up... 14:05:02 INFO - Return code: 0 14:05:02 INFO - Running post-action listener: _resource_record_post_action 14:05:02 INFO - Running post-action listener: _start_resource_monitoring 14:05:02 INFO - Starting resource monitoring. 14:05:02 INFO - [mozharness: 2017-03-28 21:05:02.468350Z] Finished create-virtualenv step (success) 14:05:02 INFO - [mozharness: 2017-03-28 21:05:02.468815Z] Running install step. 14:05:02 INFO - Running pre-action listener: _resource_record_pre_action 14:05:02 INFO - Running main action method: install 14:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:05:02 INFO - Reading from file tmpfile_stdout 14:05:02 INFO - Detecting whether we're running mozinstall >=1.0... 14:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:05:02 INFO - Reading from file tmpfile_stdout 14:05:02 INFO - Output received: 14:05:02 INFO - Usage: mozinstall [options] installer 14:05:02 INFO - Options: 14:05:02 INFO - -h, --help show this help message and exit 14:05:02 INFO - -d DEST, --destination=DEST 14:05:02 INFO - Directory to install application into. [default: 14:05:02 INFO - "/builds/slave/test"] 14:05:02 INFO - --app=APP Application being installed. [default: firefox] 14:05:02 INFO - mkdir: /builds/slave/test/build/application 14:05:02 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'] 14:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:05:18 INFO - Reading from file tmpfile_stdout 14:05:18 INFO - Output received: 14:05:18 INFO - /builds/slave/test/build/application/firefox/firefox 14:05:18 INFO - Running post-action listener: _resource_record_post_action 14:05:18 INFO - [mozharness: 2017-03-28 21:05:18.442426Z] Finished install step (success) 14:05:18 INFO - [mozharness: 2017-03-28 21:05:18.442707Z] Running run-tests step. 14:05:18 INFO - Running pre-action listener: _resource_record_pre_action 14:05:18 INFO - Running main action method: run_tests 14:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:05:18 INFO - Python 2.7.3 14:05:18 INFO - Return code: 0 14:05:18 INFO - grabbing minidump binary from tooltool 14:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:18 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 0x184a290>, '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 0x184ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184b040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:05:18 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 14:05:18 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 14:05:18 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 14:05:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:05:18 INFO - Return code: 0 14:05:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:05:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:05:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:05:18 INFO - ENV: RUST_BACKTRACE is now 1 14:05:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:05:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:05:18 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/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 14:05:18 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/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 14:05:18 INFO - Using env: {'DISPLAY': ':0', 14:05:18 INFO - 'HOME': '/home/cltbld', 14:05:18 INFO - 'LANG': 'en_US.UTF-8', 14:05:18 INFO - 'LANGUAGE': 'en_US:en', 14:05:18 INFO - 'LOGNAME': 'cltbld', 14:05:18 INFO - 'MAIL': '/var/mail/cltbld', 14:05:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:05:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:18 INFO - 'MOZ_NO_REMOTE': '1', 14:05:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:18 INFO - 'NO_EM_RESTART': '1', 14:05:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:18 INFO - 'PWD': '/builds/slave/test', 14:05:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:05:18 INFO - 'RUST_BACKTRACE': '1', 14:05:18 INFO - 'SHELL': '/bin/bash', 14:05:18 INFO - 'SHLVL': '1', 14:05:18 INFO - 'TERM': 'linux', 14:05:18 INFO - 'TMOUT': '86400', 14:05:18 INFO - 'USER': 'cltbld', 14:05:18 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746', 14:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:18 INFO - '_': '/tools/buildbot/bin/python'} 14:05:18 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/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 14:05:18 INFO - mozversion application_buildid: 20170328203107 14:05:18 INFO - mozversion application_changeset: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c 14:05:18 INFO - mozversion application_display_name: Nightly 14:05:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:05:18 INFO - mozversion application_name: Firefox 14:05:18 INFO - mozversion application_remotingname: firefox 14:05:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:05:18 INFO - mozversion application_vendor: Mozilla 14:05:18 INFO - mozversion application_version: 55.0a1 14:05:18 INFO - mozversion platform_buildid: 20170328203107 14:05:18 INFO - mozversion platform_changeset: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c 14:05:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:05:18 INFO - mozversion platform_version: 55.0a1 14:05:18 INFO - using testdate: 1490735118 14:05:18 INFO - actual date: 1490735118 14:05:18 INFO - starting webserver on 'localhost:37834' 14:05:18 INFO - SUITE-START | Running 2 tests 14:05:18 INFO - TEST-START | dromaeo_css 14:05:18 INFO - operating with platform_type : linux_ 14:05:18 INFO - Initialising browser for dromaeo_css test... 14:05:18 INFO - TEST-INFO | started process 17242 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYgyIrf/profile http://localhost:37834/getInfo.html) 14:05:23 INFO - PID 17242 | __metrics Screen width/height:1600/1200 14:05:23 INFO - PID 17242 | colorDepth:24 14:05:23 INFO - PID 17242 | Browser inner width/height: 1024/697 14:05:23 INFO - PID 17242 | __metrics 14:05:24 ERROR - PID 17242 | 1490735124142 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 14:05:24 INFO - PID 17242 | 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" 14:05:24 INFO - PID 17242 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:05:25 INFO - TEST-INFO | 17242: exit 0 14:05:25 INFO - Browser initialized. 14:05:25 INFO - Running cycle 1/1 for dromaeo_css test... 14:05:25 INFO - TEST-INFO | started process 17338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYgyIrf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:05:37 INFO - PID 17338 | RSS: Main: 149413888 14:05:37 INFO - PID 17338 | 14:08:43 INFO - PID 17338 | Cycle 1(1): loaded http://localhost:37834/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37834/tests/dromaeo/cssquery-ext.html) 14:08:45 INFO - PID 17338 | RSS: Main: 287375360 14:08:45 INFO - PID 17338 | 14:10:27 INFO - PID 17338 | ************************* 14:10:27 INFO - PID 17338 | A coding exception was thrown and uncaught in a Task. 14:10:27 INFO - PID 17338 | 14:10:27 ERROR - PID 17338 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:10:27 INFO - PID 17338 | Full stack: 14:10:27 INFO - PID 17338 | ************************* 14:10:27 INFO - PID 17338 | ************************* 14:10:27 INFO - PID 17338 | A coding exception was thrown and uncaught in a Task. 14:10:27 INFO - PID 17338 | 14:10:27 ERROR - PID 17338 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:10:27 INFO - PID 17338 | Full stack: 14:10:27 INFO - PID 17338 | ************************* 14:10:27 INFO - PID 17338 | ************************* 14:10:27 INFO - PID 17338 | A coding exception was thrown and uncaught in a Task. 14:10:27 INFO - PID 17338 | 14:10:27 ERROR - PID 17338 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:10:27 INFO - PID 17338 | Full stack: 14:10:27 INFO - PID 17338 | ************************* 14:10:27 INFO - PID 17338 | ************************* 14:10:27 INFO - PID 17338 | A coding exception was thrown and uncaught in a Task. 14:10:27 INFO - PID 17338 | 14:10:27 ERROR - PID 17338 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:10:27 INFO - PID 17338 | Full stack: 14:10:27 INFO - PID 17338 | ************************* 14:11:48 INFO - PID 17338 | Cycle 1(1): loaded http://localhost:37834/tests/dromaeo/cssquery-ext.html (next: http://localhost:37834/tests/dromaeo/cssquery-jquery.html) 14:11:48 INFO - PID 17338 | RSS: Main: 242667520 14:11:48 INFO - PID 17338 | 14:14:50 INFO - PID 17338 | Cycle 1(1): loaded http://localhost:37834/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37834/tests/dromaeo/cssquery-mootools.html) 14:14:51 INFO - PID 17338 | RSS: Main: 179376128 14:14:51 INFO - PID 17338 | 14:17:53 INFO - PID 17338 | Cycle 1(1): loaded http://localhost:37834/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37834/tests/dromaeo/cssquery-prototype.html) 14:17:54 INFO - PID 17338 | RSS: Main: 239386624 14:17:54 INFO - PID 17338 | 14:20:57 INFO - PID 17338 | Cycle 1(1): loaded http://localhost:37834/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37834/tests/dromaeo/cssquery-yui.html) 14:20:58 INFO - PID 17338 | RSS: Main: 189861888 14:20:58 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | Cycle 1(1): loaded http://localhost:37834/tests/dromaeo/cssquery-yui.html (next: http://localhost:37834/tests/dromaeo/cssquery-dojo.html) 14:24:01 INFO - PID 17338 | RSS: Main: 279203840 14:24:01 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | __start_tp_report 14:24:01 INFO - PID 17338 | _x_x_mozilla_page_load 14:24:01 INFO - PID 17338 | _x_x_mozilla_page_load_details 14:24:01 INFO - PID 17338 | |i|pagename|runs| 14:24:01 INFO - PID 17338 | |0;dojo.html;2694.4745774573958;2716.755492005719;2745.0666773297075;2751.6422865027557;2751.7798576113905;4993.975030124853;5064;5127.820526281581;5148.304978827857;5176.430592634812;5049.747512624373;5052.722100284492;5072.594192464603;5075.340205773245;5080.110980578403;4642.117997580457;4663.976680116594;4680.672352935296;4690.742009189493;4719.2921061840825;4688.29675548667;4715.2927060940965;4722.173619616553;4725.645576581753;4759.024399998009;4761.285807128924;4762.1428142934255;4766.618670506368;4766.642501812361;4771.475137734847;4744.810207591692;4758.785854636532;4760.976195119037;4768.1178981888115;4768.642351823627;4686.343911852344;4691.695039822401;4710.175719249118;4740.502247264057;4745.525447455227;5066;5127.6154288428515;5160.612954028463;5168.043911876315;5207.973960130213;5438.673679579238;5441.891162176735;5464.590155738336;5478.479544443271;5482.396936337;5029.245613158019;5363.302770639792;5410.48600382963;5421.186821976696;5440.483154100354;5172.637915345889;5191.688498690053;5215.426303106655;5216.035033518773;5222.138347172674;5687.51656109227;5688.488036076773;5688.658680479184;5690.77236910519;5693.402192769782;5140.894707637875;5389.56883449331;5424.321959755031;5425.13197888336;5480.314960629921;5363.517283444508;5446.645968012067;5465.152901300305;5553.4724201200825;5569.944300556943;5428.83713488596;5446.319210098738;5446.91829622556;5447.564194864481;5452.91820622691;5411.269478620415;5429.348478182644;5433.347998240237;5439.075357189208;5478.780848766084;4404.493483249404;4475.753833539122;4494.280915053576;4508.616767574727;4522.932156017662;3904.980475097663;3906.746061505993;3907.980460097738;3923.136909879822;3935.6457918787446;4723.7638118093955;4758.595519380891;4762.809487620526;4772.6420518461255;4803.807847686054;5396.865078373072;5397.649152805055;5455.399906010336;5457.563394928476;5597.216389705444;4719.362886010414;4724.6692731508465;4749.430068391815;4750.548697873696;4753.35829662998;4811.687240329367;4825.638077144228;4825.734584597881;4827.734474603931;4832.903341933142;5181.6890986540175;5203.92194117081;5271.630985830955;5276.762545685377;5277.076511610376;5568.053430916673;5588.608797384144;5589.357223919302;5589.608727389043;5590.413006634408;5219.425863155126;5248.107821670402;5263.6052296078715;5270.472952704852;5271.235670827632;5414.214938833926;5416.539594134542;5433.5381492572;5475.0418676730615;5484;5537.335519737658;5544.362398324083;5546.861328466659;5547;5548.4174161712435;5163.173892177234;5247.790088396421;5350.919736204038;5362.329708786402;5417.187421886749;5318.4149743527405;5342;5378.220158077135;5378.946210538001;5396.487333703292;5280.366356037146;5295.602829787705;5304.814331498379;5304.840854774363;5307.681539107666;5264.815731449381;5410.404855465974;5420.53925416344;5425.864353391197;5468.562514998711;5142.6914385137015;5250.737463126905;5267.552258058108;5298.496642818926;5313.282706834528 14:24:01 INFO - PID 17338 | |1;ext.html;654.2312782480587;716.9426445884328;730.6858051038055;1051.9158467322616;1065.350136416785;25484.872575637142;26092.869535652295;26104.34739131518;26119.73880261199;26132.216033519002;30642.540361894535;31163.844180779066;31211.843940780265;31231;31280.687193128062;7202.171750248723;7332.1934587195365;7479.439042071866;7483.4013278937555;7484.214157513465;5564.777408903639;5639.746211420475;7309.524880882752;7438.925610743904;7441.479096463223;6991.26591707871;7108.431325493974;7117.466190035768;7121.465890058266;7128.6079265640365;7114.85770284594;7154.427645788324;7182.892256616155;7183.138023437169;7189.424846012359;5526.778928842841;5632.549396048306;7166.17588977269;7242.384397326234;7246.963765181193;28176.295592610142;28654.713452865413;28662.426751465064;28724;28760.856195719098;2634.973650263492;2653.3764565327133;2656.3491944473535;2673.331667083229;2686.6507354043847;21888;22037.779622203732;22067.558648827093;22856.542869142522;23317;2971.1977766003274;2981.6571094324213;2987.507061334877;3069.079276217148;3146.040457660413;156602;156618;156852;157994.99999999886;158052.20973895057;43444;43491.782541087094;43754.562454375686;43782.56217437785;43838.86255924173;45297.547024529995;45337.09325813465;45396;45398.546014540094;45484.133848623234;12153.331566763913;12315.69210769738;12321.691957701129;12342.382880855921;12346.259224446383;9692.079252471003;9698.272629552812;9701.126898579161;9705.6603018894;9712.465814380277;10871.67384978452;10969.177311701495;12445.377731113407;12827.294498802656;13641.181529108286;5180.300659410931;5379.1662292344745;5401.405845357008;5486.533644640171;5567.916481252784;835.6991483066091;862.65062649627;870.038607338894;873.9257163141077;883.5952775414553;17967.371142009968;18018.819811801714;18200.45398638043;18706.53233669142;18894.433167005012;938.1650331205233;940.6801687426308;945.5225111318878;949.7578117579973;966.7873067925045;9598.9040109598;13830.23933683657;14029.78955315671;14214.715705685829;14233.146011239358;234257;236063;236583;236707;236757;46673;47193.76403117963;47252;47293;47301;1196.8982636475823;1230.846144231971;1234.0497816681104;1242.0746543824907;1244.085597086141;23767.405814854767;23998.76001240035;24588.87705561461;24799.62800557957;25070.247892563253;31001.37997240088;31159.688403115677;31244.843775780977;31273;31331.686683132877;237267;238318;239132;239133;239357;27876.933537310473;28099.71900281053;28127.296817578906;28133.015344462845;28169.43661126807;34047.4892876612;34613.307733845686;34643.48034779531;34666.65333346735;34700.47949280712;25741.613875792253;26384.9103388975;26414.735852641228;26743.732562674126;27264;25732.356691082125;26394.868025659747;27080.593791092757;27082.187534374;27883.024094156608;29438.705612943595;30104;30752.231194220323;30885.227869303446;30910.845445772626;25929.6110558338;26071.884420737795;26179.214623561325;26189.476210475303;26346.868265659316;19468.610627787082;21703.565928681655;21927;22181.334559963227;22191.22330718417 14:24:01 INFO - PID 17338 | |2;jquery.html;389.7602974170882;419.73416836003867;428.7856071964018;439.5472663156948;445.5188396531748;14222.857771422283;17686.734698979526;18235;19015.61968760624;19178.808211917913;16562.260962598935;16708.66582668346;17167.828321716777;17197.656046879085;17199.570010749736;5668.546516278709;5912.408759124096;5914.792982245623;5920.111983202511;5921.496672782819;3467.289205712823;3633.509476220716;5429.267048948401;5825.213596164527;5873.911891321633;5811.82564523065;5815.651060936336;5817.12743088538;5824.0390335594575;5824.504917082032;6291.968540157292;6512.316206798266;6572.474202063823;6577.901331480031;6580.045893345486;6023.367546407651;6422.389872962061;6438.420542151229;6453.322401147906;6481.189851268591;24699.506009879882;24709.752902470926;24971.110879667493;25073.122440714487;25283.367915802068;1632.4612877750433;1636.533587927435;1660.6263590692117;1706.6671998960207;1710.2303963216602;14155.787663185061;14629.487967921072;15057.924710376377;15659;15935.760963585555;1589.6343840916654;1629.167186914721;1709.6751617192695;1722.2163915418455;1737.3311275159156;82408;83320;84236.57881710552;88037;89273.55363223207;14631.230228908718;17866.821331786516;18092.90953545224;18248.726269105973;18706.906465467586;32290.83854580712;33304;33370;33444.83277583597;33508.32983340319;8323.417360784808;8571.142885711379;8796.871089475178;8821.69124080667;8990.505522196212;6740.325967403221;6812.965935170292;6967.581945083311;6987.930120698829;7012.333828286304;9242.260619150451;9366.250699943988;9599.760005999906;9678.515734335633;9840.803183936281;3512.3150985557572;3619.394604846166;3640.6177351378255;3828.502294701671;3882.650561449483;830.4933990266054;853.0914575976565;855.3798496090285;860.3418384935529;864.2610567964425;15318.770218446733;15499.922500387425;15604.609884752972;15679.294431750599;16229.59426014359;843.527624530265;852.7697521669114;852.9616167272487;857.5412154497315;878.6968495868915;8032.759017229492;8378.826964225012;8518.57407129641;8723.302135829246;8741.169588889172;429835;429878;442185;442816;442863;83887.58056209802;84178;84755;86061.56969215239;86138;1013.1185868294631;1038.5949479702922;1064.7976884392097;1066.0405634928472;1067.108964015056;13201.20792752438;13306.268155251553;13415.396307166005;13595;14146.646333841736;38671;38957.22085558216;38996;39444.80277598594;39591.80204098961;51108;51532;51828.222576662134;51859.48140518546;51991.480085198666;9544.186604545113;9854.014598540376;9865.556049977911;10141.695749127539;10259.179265658879;10891.346519208873;11481;11481.253718508271;11556.768864622492;11920.582779602672;9000.459972401677;9949.552270147717;10153;10354.689359319233;10401.219908507044;9337.626494940127;9633.566489507848;9657;9671.226301895973;9761.365511241747;9865.605375784888;9961.950190249003;10100.54547545377;10155.289129760846;10320.741981450223;7403.8149046274275;7505.211952744883;7607.543547387175;7629.923700763144;7669.884951725617;9129;9497.050294970448;9547.713568592675;9594;9855.063768941938 14:24:01 INFO - PID 17338 | |3;mootools.html;1246.819211214374;1313.3564553368847;1313.408965965316;1327.9004074694394;1329.043088975937;9368.391054581456;9708.271879609023;9774.902250977488;9776.951115244432;9778.119969202779;9535.427874327544;9752.4636145012;9813.509324533781;9815.607375704982;9837.50812459376;2242.5627002734473;2271.2731925783755;2284.1776960294283;2285.371522831218;2288.4164538042774;1844.7786265648142;2062.4019034479984;2110.546232559999;2157.8165855902266;2163.599734049206;1863.8508919286492;2070.4720296324417;2140.5932872754206;2141.860779049357;2141.9892900535474;2118.2374345235703;2135.4020874155262;2137.8289736821016;2138.6791981202787;2142.7750086240953;2037.1851259496173;2087.8538502304846;2090.3310940499046;2094.465911192651;2094.5706130243266;9984.750381240454;10077;10082.596696132146;10084.848727269096;10084.848727269096;5224.242484839677;5387.865303367408;5390.056740070472;5390.5418039466695;5401.378841433207;2991.192378057934;3201.183698156978;3202.343519578504;3209.518572214163;3212.983935080333;985.7781999050228;1065.1372388365448;1085.592902661502;1091.6888686724246;1105.745678493943;13812.654683632989;14653.853461465356;14798.70402591953;14800.11199328033;14816.259187040605;4738.47876733559;4817.253325734517;4849.418069831643;4862.902741945141;4863.781129849165;10911.672649820519;10948.616798412017;10957.452127393599;10961.561537538568;10965.61620343284;5293.470652934753;5317;5330.893382132335;5334.493223143795;5334.813281535127;4697.342372067913;4890.657653964259;4942.184539550934;4943.555080042814;4964.627652926045;3581.28374325136;3697.186618943828;3699.6115407881784;3778.301014312361;3816.2939856126704;1077.8437126616598;1095.6822521468684;1096.2162054131334;1113.6269349767917;1117.3016864459712;584.8391692284656;624.7657128576784;678.7217240931194;695.4193248637445;696.8014115976922;5112.795488180431;5161.187232852599;5326.786928522815;5327.467253274642;5472.7263636818;777.5459890005612;787.2403130978633;796.3430170109683;799.0650938402083;801.4990630855715;3317.8506967186536;3431.4681224410256;3433.4334834752485;3552.236269202134;3666.0651533859227;12273.509059637514;13100.541481048118;13124.212547247196;13200.47198112084;13216.933915330363;12885.291308978096;12959.870401295866;13115;13116.934415327862;13118.868811311764;9333.533323333942;9363.812723745486;9397.389169703947;9441.811163776823;9476.526173691287;5403.9459605403445;5428.402875683751;5431.538319242909;5520.944790552043;5623.746931388179;11585.942070289595;11596.188266821579;11631.418429078343;11803;11824.881751182378;11586.536538538701;11932.1050921183;12011.159218854946;12020.699482513008;12022.338771367658;7404;7464.216257292906;7466.813329666585;7469.439792015512;7470.589117598584;9149.725508234764;9170.945341285838;9210.769730756785;9328.300377471582;9348.485833278963;7624.80937976555;7798.37612990948;7831.686732530863;7873.921260787548;7979;7500.287472690085;7530.134034586093;7552.811179720551;7705.691772329044;7789.221077892165;6799.626020568916;6964.303569642995;6966.651667416711;6969.756058537927;6970.442364610718;4639.907201856012;4693.5071817458675;4875.658703890836;5001.924971125363;5065.7720402581235;4742.857714268577;4917.6311776616185;4958.42978057528;5012.1980483122525;5062.215356619729 14:24:01 INFO - PID 17338 | |4;prototype.html;374.5206136145733;397.62623134253784;400.86971734186386;406.721395843847;410.52789292313844;5270.868228294295;5271.683698978064;5294.126469132593;5319.521243088121;5320.414754377015;5234.084035293818;5239.816606418776;5242.737863106849;5247.895042099156;5256.947430525694;4196.139791342767;4196.706230563862;4228.873133805975;4236.788160591958;4240.279152544075;4222.451081359419;4223.809928553207;4234.17433600448;4245.8513952011535;4252.554131595257;4229.386738922854;4237.809298581571;4238.97880510597;4241.6818738594575;4249.405083288342;4125.824140120081;4202.159568086365;4228.492580890282;4247.299195632732;4258.382534532507;3814.7520411173464;3818.7517811342173;3838.2707285615643;3850.2876967761053;3851.0757418219705;5149.150390185615;5159.22611608258;5160.56135228506;5166.560842328407;5171.22431635254;4727.550882666142;4874.122657921573;4913.70517768935;4917.410290487964;4941.48114447985;5507.191332420454;5549.250851135085;5557.4439157036095;5566.137248726413;5570.136628822539;4210.431591735107;4232.217039847638;4234.724742891703;4240.1307731914885;4242.236397448489;5081.59347252219;5526.309211348582;5722.341930677942;5785.739641716091;5816.185733997244;4338.566143385699;4343.261645520269;4351.173277077345;4365.563443655609;4377.058932329501;4422.695304885066;4426.092651006536;4433.844815431445;4444.844430444984;4453.376527286183;4718;4845.854624361275;4865.927011094836;4867.853964381075;4902.950970490321;5495.488740596368;5502.9174562381595;5517.5585953123655;5531.889362212733;5568.185792731992;1507.2313120308502;1509.5924100493025;1510.2750679673818;1519.2479722537414;1524.2759689147567;1046.5918291866178;1076.2681376663943;1093.8796732359433;1096.8628921384827;1117.3351855645878;4836.903261934742;4845.878853028703;4852.8544143675745;4855.36880205571;4890.486498917634;5687.0238541276385;5694.345150307768;5708.372079071299;5710.457506536873;5723.656580605177;4735.360726301974;4840.903181936412;4868.196747536616;4881.316615673809;4885.364902562644;3005.368872536748;3015.2461884528866;3026.1829306087234;3026.4854974654363;3027.137265879214;5349.866253343697;5385.192221166856;5386.165144402624;5402.729863506809;5415.377231618336;5535.557155427556;5703.515201207861;5709.628874123168;5811.476967072899;5871.207387002785;5201.947980520222;5341.519263266325;5347.786088556414;5359.705216213146;5377.569794416516;5655.632383895034;5681.261436013292;5686.2607860977805;5688.374278829243;5701.914471282851;5423.701696406735;5532.778688852401;5554.91667624994;5559.610827242054;5563.13771365439;5302.575793936553;5332.173513105475;5334.919976200437;5357.705326207096;5375.1131063374105;4295.484541854998;4351.3472979053395;4355.956440435555;4367.6287515561535;4367.956320436882;5437.537809286255;5598.776048958159;5612.719364031864;5636.915446268227;5643.5326815027565;5467.917981230365;5477.72611369422;5480.232767412485;5483.7806487740045;5485.875395543824;5348.251244825649;5469.808556700496;5481.835544933659;5498.1752737089755;5501.4223506532835;5357.276767636319;5366.329208848894;5375.919361209507;5376.14522989568;5379.623426360117;5501.477359650827;5517.889642207214;5528.419515950928;5531.091773338192;5554.055810512304;5210.47895210476;5318.760655770422;5352.046503026445;5358.33020872391;5359.3032905722 14:24:01 INFO - PID 17338 | |5;yui.html;661.4789839524606;664.6178447392749;685.1127789512584;687.3916583823318;691.3846676457952;12537.686557836047;13050.216986980788;13058.673533161664;13060.673483162926;13107.73784524309;12666.556670516533;12813.935930320358;12856.22862628243;12859.742805143891;13439;845.873119032147;858.57500537234;875.8073223890734;879.2043200903192;880.423322723617;886.6940905387631;903.8101998580273;904.1320332480793;904.4573256046385;905.0496978172926;943.6744323208517;947.4552132523792;950.0262231212993;950.6767698982345;950.8145911547216;942.0626476655702;944.2446043165475;947.7725345916999;948.9145976862046;950.7623094226443;697.5291678117251;697.8569393274366;697.8743826111348;698.0297386632585;698.1482591238681;12420;12662.240265584094;14221.21783301918;14291.714165716732;14296;12754.298513581749;13219.339033048309;13555.59333220005;13588.116772409761;13761.714855433698;10128.797424051478;10426;10518.631847885365;10724.356538607708;10761.031507164314;2722.6845449961834;2733.5762956741737;2751.312171957011;2764.1707487753793;2773.1403264987916;63357;63647;63959.360406395805;64299.999999999534;65312.346876531104;10681.359118452918;10710.518026688818;10836.349819010884;10897.564097436014;10972;20302.796972030384;20529;20537.075831587317;20558;20736.688949665768;6655.168103987002;6688.832779180462;6706.83232919181;6742.797716068526;6924.619145947021;3359.328134373135;3415.7438192135687;3451.137215696076;3472.0625431133453;3505.1412403960685;3387.4749413840896;3406.3187362527096;3423.298223864102;3431.69114779671;3433.2446861690387;667.2693400726197;672.8822456070168;676.1345477788439;677.2581487282802;678.4640134293951;461.4001797663025;461.7552697070558;467.4180645096876;493.1247036510223;494.6240856948745;8150.836983260301;8381.287590554872;8512.70205542803;8669.826603467895;9608.519574021271;514.1952843799442;535.3014316317166;545.6316986034411;566.3996164066103;569.9173619825102;3677.8344974476213;3757.3988161893967;3762.5673047599876;4033.495813023372;4154.729942553724;55740.72129639326;55875.441245587026;57010.714946425;57588.42411575831;57598.13602795961;49806.75096624494;49901.25148122781;50149;50732.49267507352;50917.74541127271;44783.32825007627;45144.774276129065;45238.32142517865;45792.313115303965;46423.53576464192;7402.29678180572;7528.8494230116185;7652.885206722015;7718.652660630173;7893.644785984759;24021.27936161918;24299.51400972006;24538.141165059136;24765.87617061975;25307.49385012326;30288.091357259316;30382.544261835646;30472.84763576168;30588.388232234785;30747.385052299276;10082.29423940317;10119.139873110867;10146.788354096529;10335.586576536854;10481.737956550842;10797.514111865143;10812.783744325227;11001.449927503752;11067.446627668745;11117.83323250168;10022.198224142197;10365.792684146425;10502.529645849434;10535.525901334611;10879.129669626569;9646.9035309646;9686.176674982706;9980.101790838859;10245.180385568987;10259.384436933808;9505.952470237604;9508.572114254737;9625.470599117116;9792.314537982273;9809.460479673686;7892.329152022143;8100.513969161869;8150.550947133836;8360.28937540316;8645.135486451305;8468.957655211685;8498.74503764888;8644.567771611268;8741.213290803858;8834.381593288665 14:24:01 INFO - PID 17338 | __end_tp_report 14:24:01 INFO - PID 17338 | __start_cc_report 14:24:01 INFO - PID 17338 | _x_x_mozilla_cycle_collect,1462 14:24:01 INFO - PID 17338 | __end_cc_report 14:24:01 INFO - PID 17338 | __startTimestamp1490736241516__endTimestamp 14:24:01 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | ------- Summary: start ------- 14:24:01 INFO - PID 17338 | Number of tests: 6 14:24:01 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | [#0] dojo.html Cycles:165 Average:5060.33 Median:5247.79 stddev:563.81 (10.7%) stddev-sans-first:534.12 14:24:01 INFO - PID 17338 | Values: 2694.5 2716.8 2745.1 2751.6 2751.8 4994.0 5064.0 5127.8 5148.3 5176.4 5049.7 5052.7 5072.6 5075.3 5080.1 4642.1 4664.0 4680.7 4690.7 4719.3 4688.3 4715.3 4722.2 4725.6 4759.0 4761.3 4762.1 4766.6 4766.6 4771.5 4744.8 4758.8 4761.0 4768.1 4768.6 4686.3 4691.7 4710.2 4740.5 4745.5 5066.0 5127.6 5160.6 5168.0 5208.0 5438.7 5441.9 5464.6 5478.5 5482.4 5029.2 5363.3 5410.5 5421.2 5440.5 5172.6 5191.7 5215.4 5216.0 5222.1 5687.5 5688.5 5688.7 5690.8 5693.4 5140.9 5389.6 5424.3 5425.1 5480.3 5363.5 5446.6 5465.2 5553.5 5569.9 5428.8 5446.3 5446.9 5447.6 5452.9 5411.3 5429.3 5433.3 5439.1 5478.8 4404.5 4475.8 4494.3 4508.6 4522.9 3905.0 3906.7 3908.0 3923.1 3935.6 4723.8 4758.6 4762.8 4772.6 4803.8 5396.9 5397.6 5455.4 5457.6 5597.2 4719.4 4724.7 4749.4 4750.5 4753.4 4811.7 4825.6 4825.7 4827.7 4832.9 5181.7 5203.9 5271.6 5276.8 5277.1 5568.1 5588.6 5589.4 5589.6 5590.4 5219.4 5248.1 5263.6 5270.5 5271.2 5414.2 5416.5 5433.5 5475.0 5484.0 5537.3 5544.4 5546.9 5547.0 5548.4 5163.2 5247.8 5350.9 5362.3 5417.2 5318.4 5342.0 5378.2 5378.9 5396.5 5280.4 5295.6 5304.8 5304.8 5307.7 5264.8 5410.4 5420.5 5425.9 5468.6 5142.7 5250.7 5267.6 5298.5 5313.3 14:24:01 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | [#1] ext.html Cycles:180 Average:34485.48 Median:29099.78 stddev:55931.19 (192.2%) stddev-sans-first:56030.41 14:24:01 INFO - PID 17338 | Values: 654.2 716.9 730.7 1051.9 1065.4 25484.9 26092.9 26104.3 26119.7 26132.2 30642.5 31163.8 31211.8 31231.0 31280.7 7202.2 7332.2 7479.4 7483.4 7484.2 5564.8 5639.7 7309.5 7438.9 7441.5 6991.3 7108.4 7117.5 7121.5 7128.6 7114.9 7154.4 7182.9 7183.1 7189.4 5526.8 5632.5 7166.2 7242.4 7247.0 28176.3 28654.7 28662.4 28724.0 28760.9 2635.0 2653.4 2656.3 2673.3 2686.7 21888.0 22037.8 22067.6 22856.5 23317.0 2971.2 2981.7 2987.5 3069.1 3146.0 156602.0 156618.0 156852.0 157995.0 158052.2 43444.0 43491.8 43754.6 43782.6 43838.9 45297.5 45337.1 45396.0 45398.5 45484.1 12153.3 12315.7 12321.7 12342.4 12346.3 9692.1 9698.3 9701.1 9705.7 9712.5 10871.7 10969.2 12445.4 12827.3 13641.2 5180.3 5379.2 5401.4 5486.5 5567.9 835.7 862.7 870.0 873.9 883.6 17967.4 18018.8 18200.5 18706.5 18894.4 938.2 940.7 945.5 949.8 966.8 9598.9 13830.2 14029.8 14214.7 14233.1 234257.0 236063.0 236583.0 236707.0 236757.0 46673.0 47193.8 47252.0 47293.0 47301.0 1196.9 1230.8 1234.0 1242.1 1244.1 23767.4 23998.8 24588.9 24799.6 25070.2 31001.4 31159.7 31244.8 31273.0 31331.7 237267.0 238318.0 239132.0 239133.0 239357.0 27876.9 28099.7 28127.3 28133.0 28169.4 34047.5 34613.3 34643.5 34666.7 34700.5 25741.6 26384.9 26414.7 26743.7 27264.0 25732.4 26394.9 27080.6 27082.2 27883.0 29438.7 30104.0 30752.2 30885.2 30910.8 25929.6 26071.9 26179.2 26189.5 26346.9 19468.6 21703.6 21927.0 22181.3 22191.2 14:24:01 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | [#2] jquery.html Cycles:180 Average:27631.79 Median:51320.00 stddev:72304.18 (140.9%) stddev-sans-first:72478.08 14:24:01 INFO - PID 17338 | Values: 389.8 419.7 428.8 439.5 445.5 14222.9 17686.7 18235.0 19015.6 19178.8 16562.3 16708.7 17167.8 17197.7 17199.6 5668.5 5912.4 5914.8 5920.1 5921.5 3467.3 3633.5 5429.3 5825.2 5873.9 5811.8 5815.7 5817.1 5824.0 5824.5 6292.0 6512.3 6572.5 6577.9 6580.0 6023.4 6422.4 6438.4 6453.3 6481.2 24699.5 24709.8 24971.1 25073.1 25283.4 1632.5 1636.5 1660.6 1706.7 1710.2 14155.8 14629.5 15057.9 15659.0 15935.8 1589.6 1629.2 1709.7 1722.2 1737.3 82408.0 83320.0 84236.6 88037.0 89273.6 14631.2 17866.8 18092.9 18248.7 18706.9 32290.8 33304.0 33370.0 33444.8 33508.3 8323.4 8571.1 8796.9 8821.7 8990.5 6740.3 6813.0 6967.6 6987.9 7012.3 9242.3 9366.3 9599.8 9678.5 9840.8 3512.3 3619.4 3640.6 3828.5 3882.7 830.5 853.1 855.4 860.3 864.3 15318.8 15499.9 15604.6 15679.3 16229.6 843.5 852.8 853.0 857.5 878.7 8032.8 8378.8 8518.6 8723.3 8741.2 429835.0 429878.0 442185.0 442816.0 442863.0 83887.6 84178.0 84755.0 86061.6 86138.0 1013.1 1038.6 1064.8 1066.0 1067.1 13201.2 13306.3 13415.4 13595.0 14146.6 38671.0 38957.2 38996.0 39444.8 39591.8 51108.0 51532.0 51828.2 51859.5 51991.5 9544.2 9854.0 9865.6 10141.7 10259.2 10891.3 11481.0 11481.3 11556.8 11920.6 9000.5 9949.6 10153.0 10354.7 10401.2 9337.6 9633.6 9657.0 9671.2 9761.4 9865.6 9962.0 10100.5 10155.3 10320.7 7403.8 7505.2 7607.5 7629.9 7669.9 9129.0 9497.1 9547.7 9594.0 9855.1 14:24:01 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | [#3] mootools.html Cycles:180 Average:6110.45 Median:4717.91 stddev:3977.57 (84.3%) stddev-sans-first:3971.94 14:24:01 INFO - PID 17338 | Values: 1246.8 1313.4 1313.4 1327.9 1329.0 9368.4 9708.3 9774.9 9777.0 9778.1 9535.4 9752.5 9813.5 9815.6 9837.5 2242.6 2271.3 2284.2 2285.4 2288.4 1844.8 2062.4 2110.5 2157.8 2163.6 1863.9 2070.5 2140.6 2141.9 2142.0 2118.2 2135.4 2137.8 2138.7 2142.8 2037.2 2087.9 2090.3 2094.5 2094.6 9984.8 10077.0 10082.6 10084.8 10084.8 5224.2 5387.9 5390.1 5390.5 5401.4 2991.2 3201.2 3202.3 3209.5 3213.0 985.8 1065.1 1085.6 1091.7 1105.7 13812.7 14653.9 14798.7 14800.1 14816.3 4738.5 4817.3 4849.4 4862.9 4863.8 10911.7 10948.6 10957.5 10961.6 10965.6 5293.5 5317.0 5330.9 5334.5 5334.8 4697.3 4890.7 4942.2 4943.6 4964.6 3581.3 3697.2 3699.6 3778.3 3816.3 1077.8 1095.7 1096.2 1113.6 1117.3 584.8 624.8 678.7 695.4 696.8 5112.8 5161.2 5326.8 5327.5 5472.7 777.5 787.2 796.3 799.1 801.5 3317.9 3431.5 3433.4 3552.2 3666.1 12273.5 13100.5 13124.2 13200.5 13216.9 12885.3 12959.9 13115.0 13116.9 13118.9 9333.5 9363.8 9397.4 9441.8 9476.5 5403.9 5428.4 5431.5 5520.9 5623.7 11585.9 11596.2 11631.4 11803.0 11824.9 11586.5 11932.1 12011.2 12020.7 12022.3 7404.0 7464.2 7466.8 7469.4 7470.6 9149.7 9170.9 9210.8 9328.3 9348.5 7624.8 7798.4 7831.7 7873.9 7979.0 7500.3 7530.1 7552.8 7705.7 7789.2 6799.6 6964.3 6966.7 6969.8 6970.4 4639.9 4693.5 4875.7 5001.9 5065.8 4742.9 4917.6 4958.4 5012.2 5062.2 14:24:01 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | [#4] prototype.html Cycles:180 Average:4648.40 Median:5186.59 stddev:1276.84 (24.6%) stddev-sans-first:1239.47 14:24:01 INFO - PID 17338 | Values: 374.5 397.6 400.9 406.7 410.5 5270.9 5271.7 5294.1 5319.5 5320.4 5234.1 5239.8 5242.7 5247.9 5256.9 4196.1 4196.7 4228.9 4236.8 4240.3 4222.5 4223.8 4234.2 4245.9 4252.6 4229.4 4237.8 4239.0 4241.7 4249.4 4125.8 4202.2 4228.5 4247.3 4258.4 3814.8 3818.8 3838.3 3850.3 3851.1 5149.2 5159.2 5160.6 5166.6 5171.2 4727.6 4874.1 4913.7 4917.4 4941.5 5507.2 5549.3 5557.4 5566.1 5570.1 4210.4 4232.2 4234.7 4240.1 4242.2 5081.6 5526.3 5722.3 5785.7 5816.2 4338.6 4343.3 4351.2 4365.6 4377.1 4422.7 4426.1 4433.8 4444.8 4453.4 4718.0 4845.9 4865.9 4867.9 4903.0 5495.5 5502.9 5517.6 5531.9 5568.2 1507.2 1509.6 1510.3 1519.2 1524.3 1046.6 1076.3 1093.9 1096.9 1117.3 4836.9 4845.9 4852.9 4855.4 4890.5 5687.0 5694.3 5708.4 5710.5 5723.7 4735.4 4840.9 4868.2 4881.3 4885.4 3005.4 3015.2 3026.2 3026.5 3027.1 5349.9 5385.2 5386.2 5402.7 5415.4 5535.6 5703.5 5709.6 5811.5 5871.2 5201.9 5341.5 5347.8 5359.7 5377.6 5655.6 5681.3 5686.3 5688.4 5701.9 5423.7 5532.8 5554.9 5559.6 5563.1 5302.6 5332.2 5334.9 5357.7 5375.1 4295.5 4351.3 4356.0 4367.6 4368.0 5437.5 5598.8 5612.7 5636.9 5643.5 5467.9 5477.7 5480.2 5483.8 5485.9 5348.3 5469.8 5481.8 5498.2 5501.4 5357.3 5366.3 5375.9 5376.1 5379.6 5501.5 5517.9 5528.4 5531.1 5554.1 5210.5 5318.8 5352.0 5358.3 5359.3 14:24:01 INFO - PID 17338 | 14:24:01 INFO - PID 17338 | [#5] yui.html Cycles:180 Average:13273.50 Median:493.87 stddev:16180.94 (3276.3%) stddev-sans-first:16198.61 14:24:01 INFO - PID 17338 | Values: 661.5 664.6 685.1 687.4 691.4 12537.7 13050.2 13058.7 13060.7 13107.7 12666.6 12813.9 12856.2 12859.7 13439.0 845.9 858.6 875.8 879.2 880.4 886.7 903.8 904.1 904.5 905.0 943.7 947.5 950.0 950.7 950.8 942.1 944.2 947.8 948.9 950.8 697.5 697.9 697.9 698.0 698.1 12420.0 12662.2 14221.2 14291.7 14296.0 12754.3 13219.3 13555.6 13588.1 13761.7 10128.8 10426.0 10518.6 10724.4 10761.0 2722.7 2733.6 2751.3 2764.2 2773.1 63357.0 63647.0 63959.4 64300.0 65312.3 10681.4 10710.5 10836.3 10897.6 10972.0 20302.8 20529.0 20537.1 20558.0 20736.7 6655.2 6688.8 6706.8 6742.8 6924.6 3359.3 3415.7 3451.1 3472.1 3505.1 3387.5 3406.3 3423.3 3431.7 3433.2 667.3 672.9 676.1 677.3 678.5 461.4 461.8 467.4 493.1 494.6 8150.8 8381.3 8512.7 8669.8 9608.5 514.2 535.3 545.6 566.4 569.9 3677.8 3757.4 3762.6 4033.5 4154.7 55740.7 55875.4 57010.7 57588.4 57598.1 49806.8 49901.3 50149.0 50732.5 50917.7 44783.3 45144.8 45238.3 45792.3 46423.5 7402.3 7528.8 7652.9 7718.7 7893.6 24021.3 24299.5 24538.1 24765.9 25307.5 30288.1 30382.5 30472.8 30588.4 30747.4 10082.3 10119.1 10146.8 10335.6 10481.7 10797.5 10812.8 11001.4 11067.4 11117.8 10022.2 10365.8 10502.5 10535.5 10879.1 9646.9 9686.2 9980.1 10245.2 10259.4 9506.0 9508.6 9625.5 9792.3 9809.5 7892.3 8100.5 8150.6 8360.3 8645.1 8469.0 8498.7 8644.6 8741.2 8834.4 14:24:01 INFO - PID 17338 | -------- Summary: end -------- 14:24:01 INFO - PID 17338 | 14:24:02 INFO - TEST-INFO | 17338: exit 0 14:24:02 INFO - TEST-OK | dromaeo_css | took 1123709ms 14:24:02 INFO - TEST-START | kraken 14:24:02 INFO - operating with platform_type : linux_ 14:24:02 INFO - Initialising browser for kraken test... 14:24:02 INFO - TEST-INFO | started process 17664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoLmfPk/profile http://localhost:37834/getInfo.html) 14:24:06 INFO - PID 17664 | __metrics Screen width/height:1600/1200 14:24:06 INFO - PID 17664 | colorDepth:24 14:24:06 INFO - PID 17664 | Browser inner width/height: 1024/697 14:24:06 INFO - PID 17664 | __metrics 14:24:07 ERROR - PID 17664 | 1490736247360 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 14:24:07 INFO - PID 17664 | 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" 14:24:07 INFO - PID 17664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:24:08 INFO - TEST-INFO | 17664: exit 0 14:24:08 INFO - Browser initialized. 14:24:08 INFO - Running cycle 1/1 for kraken test... 14:24:08 INFO - TEST-INFO | started process 17754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoLmfPk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:24:20 INFO - PID 17754 | RSS: Main: 149901312 14:24:20 INFO - PID 17754 | 14:24:23 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37834/tests/kraken/driver.html?testName=audio-beat-detection) 14:24:24 INFO - PID 17754 | RSS: Main: 205295616 14:24:24 INFO - PID 17754 | 14:24:26 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37834/tests/kraken/driver.html?testName=audio-dft) 14:24:27 INFO - PID 17754 | RSS: Main: 185585664 14:24:27 INFO - PID 17754 | 14:24:30 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37834/tests/kraken/driver.html?testName=audio-fft) 14:24:31 INFO - PID 17754 | RSS: Main: 267251712 14:24:31 INFO - PID 17754 | 14:24:33 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37834/tests/kraken/driver.html?testName=audio-oscillator) 14:24:34 INFO - PID 17754 | RSS: Main: 177430528 14:24:34 INFO - PID 17754 | 14:24:36 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37834/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:24:37 INFO - PID 17754 | RSS: Main: 181846016 14:24:37 INFO - PID 17754 | 14:24:40 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37834/tests/kraken/driver.html?testName=imaging-darkroom) 14:24:40 INFO - PID 17754 | RSS: Main: 220446720 14:24:40 INFO - PID 17754 | 14:24:44 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37834/tests/kraken/driver.html?testName=imaging-desaturate) 14:24:45 INFO - PID 17754 | RSS: Main: 214638592 14:24:45 INFO - PID 17754 | 14:24:48 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37834/tests/kraken/driver.html?testName=json-parse-financial) 14:24:48 INFO - PID 17754 | RSS: Main: 217235456 14:24:48 INFO - PID 17754 | 14:24:50 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37834/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:24:51 INFO - PID 17754 | RSS: Main: 180506624 14:24:51 INFO - PID 17754 | 14:24:53 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37834/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:24:53 INFO - PID 17754 | RSS: Main: 188506112 14:24:53 INFO - PID 17754 | 14:24:55 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37834/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:24:56 INFO - PID 17754 | RSS: Main: 196956160 14:24:56 INFO - PID 17754 | 14:24:59 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37834/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:25:00 INFO - PID 17754 | RSS: Main: 207065088 14:25:00 INFO - PID 17754 | 14:25:03 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37834/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:25:04 INFO - PID 17754 | RSS: Main: 203300864 14:25:04 INFO - PID 17754 | 14:25:05 INFO - PID 17754 | Cycle 1(1): loaded http://localhost:37834/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37834/tests/kraken/driver.html?testName=ai-astar) 14:25:06 INFO - PID 17754 | RSS: Main: 272023552 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | __start_tp_report 14:25:06 INFO - PID 17754 | _x_x_mozilla_page_load 14:25:06 INFO - PID 17754 | _x_x_mozilla_page_load_details 14:25:06 INFO - PID 17754 | |i|pagename|runs| 14:25:06 INFO - PID 17754 | |0;ai-astar;102;112;111;112;111;112;113;111;111;111 14:25:06 INFO - PID 17754 | |1;audio-beat-detection;147;129;126;173;126;125;126;124;153;131 14:25:06 INFO - PID 17754 | |2;audio-dft;165;164;164;166;166;164;165;165;164;164 14:25:06 INFO - PID 17754 | |3;audio-fft;75;76;75;116;77;76;76;76;93;78 14:25:06 INFO - PID 17754 | |4;audio-oscillator;85;86;112;86;84;83;98;84;86;110 14:25:06 INFO - PID 17754 | |5;imaging-gaussian-blur;114;114;114;115;114;114;113;114;113;114 14:25:06 INFO - PID 17754 | |6;imaging-darkroom;151;151;151;150;151;150;151;151;152;151 14:25:06 INFO - PID 17754 | |7;imaging-desaturate;95;98;94;94;94;94;95;94;94;94 14:25:06 INFO - PID 17754 | |8;json-parse-financial;66;66;65;65;66;75;67;66;67;66 14:25:06 INFO - PID 17754 | |9;json-stringify-tinderbox;56;55;55;58;56;56;57;55;55;56 14:25:06 INFO - PID 17754 | |10;stanford-crypto-aes;82;84;82;85;84;83;86;82;84;85 14:25:06 INFO - PID 17754 | |11;stanford-crypto-ccm;150;139;141;137;142;143;139;139;183;138 14:25:06 INFO - PID 17754 | |12;stanford-crypto-pbkdf2;196;205;197;198;198;205;195;198;196;196 14:25:06 INFO - PID 17754 | |13;stanford-crypto-sha256-iterative;72;73;73;75;72;73;75;71;75;70 14:25:06 INFO - PID 17754 | __end_tp_report 14:25:06 INFO - PID 17754 | __start_cc_report 14:25:06 INFO - PID 17754 | _x_x_mozilla_cycle_collect,2022 14:25:06 INFO - PID 17754 | __end_cc_report 14:25:06 INFO - PID 17754 | __startTimestamp1490736306109__endTimestamp 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | ------- Summary: start ------- 14:25:06 INFO - PID 17754 | Number of tests: 14 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.50 stddev:3.10 (2.8%) stddev-sans-first:0.73 14:25:06 INFO - PID 17754 | Values: 102.0 112.0 111.0 112.0 111.0 112.0 113.0 111.0 111.0 111.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:130.00 stddev:16.39 (12.6%) stddev-sans-first:16.90 14:25:06 INFO - PID 17754 | Values: 147.0 129.0 126.0 173.0 126.0 125.0 126.0 124.0 153.0 131.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#2] audio-dft Cycles:10 Average:164.70 Median:165.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 14:25:06 INFO - PID 17754 | Values: 165.0 164.0 164.0 166.0 166.0 164.0 165.0 165.0 164.0 164.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:13.16 (17.3%) stddev-sans-first:13.73 14:25:06 INFO - PID 17754 | Values: 75.0 76.0 75.0 116.0 77.0 76.0 76.0 76.0 93.0 78.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:85.50 stddev:11.17 (13.1%) stddev-sans-first:11.60 14:25:06 INFO - PID 17754 | Values: 85.0 86.0 112.0 86.0 84.0 83.0 98.0 84.0 86.0 110.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 14:25:06 INFO - PID 17754 | Values: 114.0 114.0 114.0 115.0 114.0 114.0 113.0 114.0 113.0 114.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 14:25:06 INFO - PID 17754 | Values: 151.0 151.0 151.0 150.0 151.0 150.0 151.0 151.0 152.0 151.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.00 stddev:1.26 (1.3%) stddev-sans-first:1.33 14:25:06 INFO - PID 17754 | Values: 95.0 98.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:66.00 stddev:2.92 (4.4%) stddev-sans-first:3.08 14:25:06 INFO - PID 17754 | Values: 66.0 66.0 65.0 65.0 66.0 75.0 67.0 66.0 67.0 66.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#9] json-stringify-tinderbox Cycles:10 Average:55.90 Median:56.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 14:25:06 INFO - PID 17754 | Values: 56.0 55.0 55.0 58.0 56.0 56.0 57.0 55.0 55.0 56.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.42 (1.7%) stddev-sans-first:1.36 14:25:06 INFO - PID 17754 | Values: 82.0 84.0 82.0 85.0 84.0 83.0 86.0 82.0 84.0 85.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:141.50 stddev:13.82 (9.8%) stddev-sans-first:14.54 14:25:06 INFO - PID 17754 | Values: 150.0 139.0 141.0 137.0 142.0 143.0 139.0 139.0 183.0 138.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.40 Median:198.00 stddev:3.63 (1.8%) stddev-sans-first:3.74 14:25:06 INFO - PID 17754 | Values: 196.0 205.0 197.0 198.0 198.0 205.0 195.0 198.0 196.0 196.0 14:25:06 INFO - PID 17754 | 14:25:06 INFO - PID 17754 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:73.00 stddev:1.73 (2.4%) stddev-sans-first:1.80 14:25:06 INFO - PID 17754 | Values: 72.0 73.0 73.0 75.0 72.0 73.0 75.0 71.0 75.0 70.0 14:25:06 INFO - PID 17754 | -------- Summary: end -------- 14:25:06 INFO - PID 17754 | 14:25:07 INFO - TEST-INFO | 17754: exit 0 14:25:07 INFO - TEST-OK | kraken | took 64921ms 14:25:07 INFO - SUITE-END | took 1188s 14:25:07 INFO - Completed test suite (00:19:49) 14:25:07 INFO - javascript benchmark 14:25:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6571.099245633369, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2694.4745774573958, 2716.755492005719, 2745.0666773297075, 2751.6422865027557, 2751.7798576113905, 4993.975030124853, 5064.0, 5127.820526281581, 5148.304978827857, 5176.430592634812, 5049.747512624373, 5052.722100284492, 5072.594192464603, 5075.340205773245, 5080.110980578403, 4642.117997580457, 4663.976680116594, 4680.672352935296, 4690.742009189493, 4719.2921061840825, 4688.29675548667, 4715.2927060940965, 4722.173619616553, 4725.645576581753, 4759.024399998009, 4761.285807128924, 4762.1428142934255, 4766.618670506368, 4766.642501812361, 4771.475137734847, 4744.810207591692, 4758.785854636532, 4760.976195119037, 4768.1178981888115, 4768.642351823627, 4686.343911852344, 4691.695039822401, 4710.175719249118, 4740.502247264057, 4745.525447455227, 5066.0, 5127.6154288428515, 5160.612954028463, 5168.043911876315, 5207.973960130213, 5438.673679579238, 5441.891162176735, 5464.590155738336, 5478.479544443271, 5482.396936337, 5029.245613158019, 5363.302770639792, 5410.48600382963, 5421.186821976696, 5440.483154100354, 5172.637915345889, 5191.688498690053, 5215.426303106655, 5216.035033518773, 5222.138347172674, 5687.51656109227, 5688.488036076773, 5688.658680479184, 5690.77236910519, 5693.402192769782, 5140.894707637875, 5389.56883449331, 5424.321959755031, 5425.13197888336, 5480.314960629921, 5363.517283444508, 5446.645968012067, 5465.152901300305, 5553.4724201200825, 5569.944300556943, 5428.83713488596, 5446.319210098738, 5446.91829622556, 5447.564194864481, 5452.91820622691, 5411.269478620415, 5429.348478182644, 5433.347998240237, 5439.075357189208, 5478.780848766084, 4404.493483249404, 4475.753833539122, 4494.280915053576, 4508.616767574727, 4522.932156017662, 3904.980475097663, 3906.746061505993, 3907.980460097738, 3923.136909879822, 3935.6457918787446, 4723.7638118093955, 4758.595519380891, 4762.809487620526, 4772.6420518461255, 4803.807847686054, 5396.865078373072, 5397.649152805055, 5455.399906010336, 5457.563394928476, 5597.216389705444, 4719.362886010414, 4724.6692731508465, 4749.430068391815, 4750.548697873696, 4753.35829662998, 4811.687240329367, 4825.638077144228, 4825.734584597881, 4827.734474603931, 4832.903341933142, 5181.6890986540175, 5203.92194117081, 5271.630985830955, 5276.762545685377, 5277.076511610376, 5568.053430916673, 5588.608797384144, 5589.357223919302, 5589.608727389043, 5590.413006634408, 5219.425863155126, 5248.107821670402, 5263.6052296078715, 5270.472952704852, 5271.235670827632, 5414.214938833926, 5416.539594134542, 5433.5381492572, 5475.0418676730615, 5484.0, 5537.335519737658, 5544.362398324083, 5546.861328466659, 5547.0, 5548.4174161712435, 5163.173892177234, 5247.790088396421, 5350.919736204038, 5362.329708786402, 5417.187421886749, 5318.4149743527405, 5342.0, 5378.220158077135, 5378.946210538001, 5396.487333703292, 5280.366356037146, 5295.602829787705, 5304.814331498379, 5304.840854774363, 5307.681539107666, 5264.815731449381, 5410.404855465974, 5420.53925416344, 5425.864353391197, 5468.562514998711, 5142.6914385137015, 5250.737463126905, 5267.552258058108, 5298.496642818926, 5313.282706834528], "value": 5021.2631713363035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.2312782480587, 716.9426445884328, 730.6858051038055, 1051.9158467322616, 1065.350136416785, 25484.872575637142, 26092.869535652295, 26104.34739131518, 26119.73880261199, 26132.216033519002, 30642.540361894535, 31163.844180779066, 31211.843940780265, 31231.0, 31280.687193128062, 7202.171750248723, 7332.1934587195365, 7479.439042071866, 7483.4013278937555, 7484.214157513465, 5564.777408903639, 5639.746211420475, 7309.524880882752, 7438.925610743904, 7441.479096463223, 6991.26591707871, 7108.431325493974, 7117.466190035768, 7121.465890058266, 7128.6079265640365, 7114.85770284594, 7154.427645788324, 7182.892256616155, 7183.138023437169, 7189.424846012359, 5526.778928842841, 5632.549396048306, 7166.17588977269, 7242.384397326234, 7246.963765181193, 28176.295592610142, 28654.713452865413, 28662.426751465064, 28724.0, 28760.856195719098, 2634.973650263492, 2653.3764565327133, 2656.3491944473535, 2673.331667083229, 2686.6507354043847, 21888.0, 22037.779622203732, 22067.558648827093, 22856.542869142522, 23317.0, 2971.1977766003274, 2981.6571094324213, 2987.507061334877, 3069.079276217148, 3146.040457660413, 156602.0, 156618.0, 156852.0, 157994.99999999886, 158052.20973895057, 43444.0, 43491.782541087094, 43754.562454375686, 43782.56217437785, 43838.86255924173, 45297.547024529995, 45337.09325813465, 45396.0, 45398.546014540094, 45484.133848623234, 12153.331566763913, 12315.69210769738, 12321.691957701129, 12342.382880855921, 12346.259224446383, 9692.079252471003, 9698.272629552812, 9701.126898579161, 9705.6603018894, 9712.465814380277, 10871.67384978452, 10969.177311701495, 12445.377731113407, 12827.294498802656, 13641.181529108286, 5180.300659410931, 5379.1662292344745, 5401.405845357008, 5486.533644640171, 5567.916481252784, 835.6991483066091, 862.65062649627, 870.038607338894, 873.9257163141077, 883.5952775414553, 17967.371142009968, 18018.819811801714, 18200.45398638043, 18706.53233669142, 18894.433167005012, 938.1650331205233, 940.6801687426308, 945.5225111318878, 949.7578117579973, 966.7873067925045, 9598.9040109598, 13830.23933683657, 14029.78955315671, 14214.715705685829, 14233.146011239358, 234257.0, 236063.0, 236583.0, 236707.0, 236757.0, 46673.0, 47193.76403117963, 47252.0, 47293.0, 47301.0, 1196.8982636475823, 1230.846144231971, 1234.0497816681104, 1242.0746543824907, 1244.085597086141, 23767.405814854767, 23998.76001240035, 24588.87705561461, 24799.62800557957, 25070.247892563253, 31001.37997240088, 31159.688403115677, 31244.843775780977, 31273.0, 31331.686683132877, 237267.0, 238318.0, 239132.0, 239133.0, 239357.0, 27876.933537310473, 28099.71900281053, 28127.296817578906, 28133.015344462845, 28169.43661126807, 34047.4892876612, 34613.307733845686, 34643.48034779531, 34666.65333346735, 34700.47949280712, 25741.613875792253, 26384.9103388975, 26414.735852641228, 26743.732562674126, 27264.0, 25732.356691082125, 26394.868025659747, 27080.593791092757, 27082.187534374, 27883.024094156608, 29438.705612943595, 30104.0, 30752.231194220323, 30885.227869303446, 30910.845445772626, 25929.6110558338, 26071.884420737795, 26179.214623561325, 26189.476210475303, 26346.868265659316, 19468.610627787082, 21703.565928681655, 21927.0, 22181.334559963227, 22191.22330718417], "value": 14602.634544070568, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.7602974170882, 419.73416836003867, 428.7856071964018, 439.5472663156948, 445.5188396531748, 14222.857771422283, 17686.734698979526, 18235.0, 19015.61968760624, 19178.808211917913, 16562.260962598935, 16708.66582668346, 17167.828321716777, 17197.656046879085, 17199.570010749736, 5668.546516278709, 5912.408759124096, 5914.792982245623, 5920.111983202511, 5921.496672782819, 3467.289205712823, 3633.509476220716, 5429.267048948401, 5825.213596164527, 5873.911891321633, 5811.82564523065, 5815.651060936336, 5817.12743088538, 5824.0390335594575, 5824.504917082032, 6291.968540157292, 6512.316206798266, 6572.474202063823, 6577.901331480031, 6580.045893345486, 6023.367546407651, 6422.389872962061, 6438.420542151229, 6453.322401147906, 6481.189851268591, 24699.506009879882, 24709.752902470926, 24971.110879667493, 25073.122440714487, 25283.367915802068, 1632.4612877750433, 1636.533587927435, 1660.6263590692117, 1706.6671998960207, 1710.2303963216602, 14155.787663185061, 14629.487967921072, 15057.924710376377, 15659.0, 15935.760963585555, 1589.6343840916654, 1629.167186914721, 1709.6751617192695, 1722.2163915418455, 1737.3311275159156, 82408.0, 83320.0, 84236.57881710552, 88037.0, 89273.55363223207, 14631.230228908718, 17866.821331786516, 18092.90953545224, 18248.726269105973, 18706.906465467586, 32290.83854580712, 33304.0, 33370.0, 33444.83277583597, 33508.32983340319, 8323.417360784808, 8571.142885711379, 8796.871089475178, 8821.69124080667, 8990.505522196212, 6740.325967403221, 6812.965935170292, 6967.581945083311, 6987.930120698829, 7012.333828286304, 9242.260619150451, 9366.250699943988, 9599.760005999906, 9678.515734335633, 9840.803183936281, 3512.3150985557572, 3619.394604846166, 3640.6177351378255, 3828.502294701671, 3882.650561449483, 830.4933990266054, 853.0914575976565, 855.3798496090285, 860.3418384935529, 864.2610567964425, 15318.770218446733, 15499.922500387425, 15604.609884752972, 15679.294431750599, 16229.59426014359, 843.527624530265, 852.7697521669114, 852.9616167272487, 857.5412154497315, 878.6968495868915, 8032.759017229492, 8378.826964225012, 8518.57407129641, 8723.302135829246, 8741.169588889172, 429835.0, 429878.0, 442185.0, 442816.0, 442863.0, 83887.58056209802, 84178.0, 84755.0, 86061.56969215239, 86138.0, 1013.1185868294631, 1038.5949479702922, 1064.7976884392097, 1066.0405634928472, 1067.108964015056, 13201.20792752438, 13306.268155251553, 13415.396307166005, 13595.0, 14146.646333841736, 38671.0, 38957.22085558216, 38996.0, 39444.80277598594, 39591.80204098961, 51108.0, 51532.0, 51828.222576662134, 51859.48140518546, 51991.480085198666, 9544.186604545113, 9854.014598540376, 9865.556049977911, 10141.695749127539, 10259.179265658879, 10891.346519208873, 11481.0, 11481.253718508271, 11556.768864622492, 11920.582779602672, 9000.459972401677, 9949.552270147717, 10153.0, 10354.689359319233, 10401.219908507044, 9337.626494940127, 9633.566489507848, 9657.0, 9671.226301895973, 9761.365511241747, 9865.605375784888, 9961.950190249003, 10100.54547545377, 10155.289129760846, 10320.741981450223, 7403.8149046274275, 7505.211952744883, 7607.543547387175, 7629.923700763144, 7669.884951725617, 9129.0, 9497.050294970448, 9547.713568592675, 9594.0, 9855.063768941938], "value": 9322.510236536327, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1246.819211214374, 1313.3564553368847, 1313.408965965316, 1327.9004074694394, 1329.043088975937, 9368.391054581456, 9708.271879609023, 9774.902250977488, 9776.951115244432, 9778.119969202779, 9535.427874327544, 9752.4636145012, 9813.509324533781, 9815.607375704982, 9837.50812459376, 2242.5627002734473, 2271.2731925783755, 2284.1776960294283, 2285.371522831218, 2288.4164538042774, 1844.7786265648142, 2062.4019034479984, 2110.546232559999, 2157.8165855902266, 2163.599734049206, 1863.8508919286492, 2070.4720296324417, 2140.5932872754206, 2141.860779049357, 2141.9892900535474, 2118.2374345235703, 2135.4020874155262, 2137.8289736821016, 2138.6791981202787, 2142.7750086240953, 2037.1851259496173, 2087.8538502304846, 2090.3310940499046, 2094.465911192651, 2094.5706130243266, 9984.750381240454, 10077.0, 10082.596696132146, 10084.848727269096, 10084.848727269096, 5224.242484839677, 5387.865303367408, 5390.056740070472, 5390.5418039466695, 5401.378841433207, 2991.192378057934, 3201.183698156978, 3202.343519578504, 3209.518572214163, 3212.983935080333, 985.7781999050228, 1065.1372388365448, 1085.592902661502, 1091.6888686724246, 1105.745678493943, 13812.654683632989, 14653.853461465356, 14798.70402591953, 14800.11199328033, 14816.259187040605, 4738.47876733559, 4817.253325734517, 4849.418069831643, 4862.902741945141, 4863.781129849165, 10911.672649820519, 10948.616798412017, 10957.452127393599, 10961.561537538568, 10965.61620343284, 5293.470652934753, 5317.0, 5330.893382132335, 5334.493223143795, 5334.813281535127, 4697.342372067913, 4890.657653964259, 4942.184539550934, 4943.555080042814, 4964.627652926045, 3581.28374325136, 3697.186618943828, 3699.6115407881784, 3778.301014312361, 3816.2939856126704, 1077.8437126616598, 1095.6822521468684, 1096.2162054131334, 1113.6269349767917, 1117.3016864459712, 584.8391692284656, 624.7657128576784, 678.7217240931194, 695.4193248637445, 696.8014115976922, 5112.795488180431, 5161.187232852599, 5326.786928522815, 5327.467253274642, 5472.7263636818, 777.5459890005612, 787.2403130978633, 796.3430170109683, 799.0650938402083, 801.4990630855715, 3317.8506967186536, 3431.4681224410256, 3433.4334834752485, 3552.236269202134, 3666.0651533859227, 12273.509059637514, 13100.541481048118, 13124.212547247196, 13200.47198112084, 13216.933915330363, 12885.291308978096, 12959.870401295866, 13115.0, 13116.934415327862, 13118.868811311764, 9333.533323333942, 9363.812723745486, 9397.389169703947, 9441.811163776823, 9476.526173691287, 5403.9459605403445, 5428.402875683751, 5431.538319242909, 5520.944790552043, 5623.746931388179, 11585.942070289595, 11596.188266821579, 11631.418429078343, 11803.0, 11824.881751182378, 11586.536538538701, 11932.1050921183, 12011.159218854946, 12020.699482513008, 12022.338771367658, 7404.0, 7464.216257292906, 7466.813329666585, 7469.439792015512, 7470.589117598584, 9149.725508234764, 9170.945341285838, 9210.769730756785, 9328.300377471582, 9348.485833278963, 7624.80937976555, 7798.37612990948, 7831.686732530863, 7873.921260787548, 7979.0, 7500.287472690085, 7530.134034586093, 7552.811179720551, 7705.691772329044, 7789.221077892165, 6799.626020568916, 6964.303569642995, 6966.651667416711, 6969.756058537927, 6970.442364610718, 4639.907201856012, 4693.5071817458675, 4875.658703890836, 5001.924971125363, 5065.7720402581235, 4742.857714268577, 4917.6311776616185, 4958.42978057528, 5012.1980483122525, 5062.215356619729], "value": 4582.857256795318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.5206136145733, 397.62623134253784, 400.86971734186386, 406.721395843847, 410.52789292313844, 5270.868228294295, 5271.683698978064, 5294.126469132593, 5319.521243088121, 5320.414754377015, 5234.084035293818, 5239.816606418776, 5242.737863106849, 5247.895042099156, 5256.947430525694, 4196.139791342767, 4196.706230563862, 4228.873133805975, 4236.788160591958, 4240.279152544075, 4222.451081359419, 4223.809928553207, 4234.17433600448, 4245.8513952011535, 4252.554131595257, 4229.386738922854, 4237.809298581571, 4238.97880510597, 4241.6818738594575, 4249.405083288342, 4125.824140120081, 4202.159568086365, 4228.492580890282, 4247.299195632732, 4258.382534532507, 3814.7520411173464, 3818.7517811342173, 3838.2707285615643, 3850.2876967761053, 3851.0757418219705, 5149.150390185615, 5159.22611608258, 5160.56135228506, 5166.560842328407, 5171.22431635254, 4727.550882666142, 4874.122657921573, 4913.70517768935, 4917.410290487964, 4941.48114447985, 5507.191332420454, 5549.250851135085, 5557.4439157036095, 5566.137248726413, 5570.136628822539, 4210.431591735107, 4232.217039847638, 4234.724742891703, 4240.1307731914885, 4242.236397448489, 5081.59347252219, 5526.309211348582, 5722.341930677942, 5785.739641716091, 5816.185733997244, 4338.566143385699, 4343.261645520269, 4351.173277077345, 4365.563443655609, 4377.058932329501, 4422.695304885066, 4426.092651006536, 4433.844815431445, 4444.844430444984, 4453.376527286183, 4718.0, 4845.854624361275, 4865.927011094836, 4867.853964381075, 4902.950970490321, 5495.488740596368, 5502.9174562381595, 5517.5585953123655, 5531.889362212733, 5568.185792731992, 1507.2313120308502, 1509.5924100493025, 1510.2750679673818, 1519.2479722537414, 1524.2759689147567, 1046.5918291866178, 1076.2681376663943, 1093.8796732359433, 1096.8628921384827, 1117.3351855645878, 4836.903261934742, 4845.878853028703, 4852.8544143675745, 4855.36880205571, 4890.486498917634, 5687.0238541276385, 5694.345150307768, 5708.372079071299, 5710.457506536873, 5723.656580605177, 4735.360726301974, 4840.903181936412, 4868.196747536616, 4881.316615673809, 4885.364902562644, 3005.368872536748, 3015.2461884528866, 3026.1829306087234, 3026.4854974654363, 3027.137265879214, 5349.866253343697, 5385.192221166856, 5386.165144402624, 5402.729863506809, 5415.377231618336, 5535.557155427556, 5703.515201207861, 5709.628874123168, 5811.476967072899, 5871.207387002785, 5201.947980520222, 5341.519263266325, 5347.786088556414, 5359.705216213146, 5377.569794416516, 5655.632383895034, 5681.261436013292, 5686.2607860977805, 5688.374278829243, 5701.914471282851, 5423.701696406735, 5532.778688852401, 5554.91667624994, 5559.610827242054, 5563.13771365439, 5302.575793936553, 5332.173513105475, 5334.919976200437, 5357.705326207096, 5375.1131063374105, 4295.484541854998, 4351.3472979053395, 4355.956440435555, 4367.6287515561535, 4367.956320436882, 5437.537809286255, 5598.776048958159, 5612.719364031864, 5636.915446268227, 5643.5326815027565, 5467.917981230365, 5477.72611369422, 5480.232767412485, 5483.7806487740045, 5485.875395543824, 5348.251244825649, 5469.808556700496, 5481.835544933659, 5498.1752737089755, 5501.4223506532835, 5357.276767636319, 5366.329208848894, 5375.919361209507, 5376.14522989568, 5379.623426360117, 5501.477359650827, 5517.889642207214, 5528.419515950928, 5531.091773338192, 5554.055810512304, 5210.47895210476, 5318.760655770422, 5352.046503026445, 5358.33020872391, 5359.3032905722], "value": 4267.900794083214, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.4789839524606, 664.6178447392749, 685.1127789512584, 687.3916583823318, 691.3846676457952, 12537.686557836047, 13050.216986980788, 13058.673533161664, 13060.673483162926, 13107.73784524309, 12666.556670516533, 12813.935930320358, 12856.22862628243, 12859.742805143891, 13439.0, 845.873119032147, 858.57500537234, 875.8073223890734, 879.2043200903192, 880.423322723617, 886.6940905387631, 903.8101998580273, 904.1320332480793, 904.4573256046385, 905.0496978172926, 943.6744323208517, 947.4552132523792, 950.0262231212993, 950.6767698982345, 950.8145911547216, 942.0626476655702, 944.2446043165475, 947.7725345916999, 948.9145976862046, 950.7623094226443, 697.5291678117251, 697.8569393274366, 697.8743826111348, 698.0297386632585, 698.1482591238681, 12420.0, 12662.240265584094, 14221.21783301918, 14291.714165716732, 14296.0, 12754.298513581749, 13219.339033048309, 13555.59333220005, 13588.116772409761, 13761.714855433698, 10128.797424051478, 10426.0, 10518.631847885365, 10724.356538607708, 10761.031507164314, 2722.6845449961834, 2733.5762956741737, 2751.312171957011, 2764.1707487753793, 2773.1403264987916, 63357.0, 63647.0, 63959.360406395805, 64299.999999999534, 65312.346876531104, 10681.359118452918, 10710.518026688818, 10836.349819010884, 10897.564097436014, 10972.0, 20302.796972030384, 20529.0, 20537.075831587317, 20558.0, 20736.688949665768, 6655.168103987002, 6688.832779180462, 6706.83232919181, 6742.797716068526, 6924.619145947021, 3359.328134373135, 3415.7438192135687, 3451.137215696076, 3472.0625431133453, 3505.1412403960685, 3387.4749413840896, 3406.3187362527096, 3423.298223864102, 3431.69114779671, 3433.2446861690387, 667.2693400726197, 672.8822456070168, 676.1345477788439, 677.2581487282802, 678.4640134293951, 461.4001797663025, 461.7552697070558, 467.4180645096876, 493.1247036510223, 494.6240856948745, 8150.836983260301, 8381.287590554872, 8512.70205542803, 8669.826603467895, 9608.519574021271, 514.1952843799442, 535.3014316317166, 545.6316986034411, 566.3996164066103, 569.9173619825102, 3677.8344974476213, 3757.3988161893967, 3762.5673047599876, 4033.495813023372, 4154.729942553724, 55740.72129639326, 55875.441245587026, 57010.714946425, 57588.42411575831, 57598.13602795961, 49806.75096624494, 49901.25148122781, 50149.0, 50732.49267507352, 50917.74541127271, 44783.32825007627, 45144.774276129065, 45238.32142517865, 45792.313115303965, 46423.53576464192, 7402.29678180572, 7528.8494230116185, 7652.885206722015, 7718.652660630173, 7893.644785984759, 24021.27936161918, 24299.51400972006, 24538.141165059136, 24765.87617061975, 25307.49385012326, 30288.091357259316, 30382.544261835646, 30472.84763576168, 30588.388232234785, 30747.385052299276, 10082.29423940317, 10119.139873110867, 10146.788354096529, 10335.586576536854, 10481.737956550842, 10797.514111865143, 10812.783744325227, 11001.449927503752, 11067.446627668745, 11117.83323250168, 10022.198224142197, 10365.792684146425, 10502.529645849434, 10535.525901334611, 10879.129669626569, 9646.9035309646, 9686.176674982706, 9980.101790838859, 10245.180385568987, 10259.384436933808, 9505.952470237604, 9508.572114254737, 9625.470599117116, 9792.314537982273, 9809.460479673686, 7892.329152022143, 8100.513969161869, 8150.550947133836, 8360.28937540316, 8645.135486451305, 8468.957655211685, 8498.74503764888, 8644.567771611268, 8741.213290803858, 8834.381593288665], "value": 6020.9570929303645, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 112.0, 111.0, 112.0, 113.0, 111.0, 111.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 129.0, 126.0, 173.0, 126.0, 125.0, 126.0, 124.0, 153.0, 131.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 166.0, 166.0, 164.0, 165.0, 165.0, 164.0, 164.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 116.0, 77.0, 76.0, 76.0, 76.0, 93.0, 78.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 112.0, 86.0, 84.0, 83.0, 98.0, 84.0, 86.0, 110.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 150.0, 151.0, 150.0, 151.0, 151.0, 152.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 66.0, 75.0, 67.0, 66.0, 67.0, 66.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 58.0, 56.0, 56.0, 57.0, 55.0, 55.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 82.0, 85.0, 84.0, 83.0, 86.0, 82.0, 84.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 139.0, 141.0, 137.0, 142.0, 143.0, 139.0, 139.0, 183.0, 138.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 205.0, 197.0, 198.0, 198.0, 205.0, 195.0, 198.0, 196.0, 196.0], "value": 198.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 73.0, 75.0, 72.0, 73.0, 75.0, 71.0, 75.0, 70.0], "value": 72.9, "unit": "score"}]}]} 14:25:07 INFO - Return code: 0 14:25:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:25:07 ERROR - # TBPL SUCCESS # 14:25:07 INFO - Running post-action listener: _resource_record_post_action 14:25:07 INFO - [mozharness: 2017-03-28 21:25:07.930899Z] Finished run-tests step (success) 14:25:07 INFO - Running post-run listener: _resource_record_post_run 14:25:08 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 16384; Write bytes: 366731264; Read time: 240; Write time: 252820 14:25:08 INFO - TinderboxPrint: CPU usage
13.0% 14:25:08 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 240 14:25:08 INFO - TinderboxPrint: I/O write bytes / time
366,731,264 / 252,820 14:25:08 INFO - TinderboxPrint: CPU idle
8,365.0 (87.0%) 14:25:08 INFO - TinderboxPrint: CPU user
1,205.1 (12.5%) 14:25:08 INFO - TinderboxPrint: Swap in / out
0 / 0 14:25:08 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 106110976; Read time: 28; Write time: 99800 14:25:08 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 12288; Write bytes: 260620288; Read time: 212; Write time: 153020 14:25:08 INFO - Running post-run listener: _upload_blobber_files 14:25:08 INFO - Blob upload gear active. 14:25:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:25:08 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 14:25:08 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'] 14:25:08 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 14:25:08 INFO - (blobuploader) - INFO - Open directory for files ... 14:25:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:25:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:25:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:09 INFO - (blobuploader) - INFO - Done attempting. 14:25:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:25:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:25:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:10 INFO - (blobuploader) - INFO - Done attempting. 14:25:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:25:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:25:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:13 INFO - (blobuploader) - INFO - Done attempting. 14:25:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:25:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:25:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:14 INFO - (blobuploader) - INFO - Done attempting. 14:25:14 INFO - (blobuploader) - INFO - Iteration through files over. 14:25:14 INFO - Return code: 0 14:25:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:25:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:25:14 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55b24d8fb29fcfd9109555e9c78a21a5aae83f6de1d2827d713473654e074d28768a4af677cae326daffcc7983aa6575e7d3c334c773bf9a2f07280b32aab6b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/087f06ac72f47b9eac85b2d6157e42f105650e40223f3c5c6a34f5b9d83112330d39b74eb96ab712d553ca663997a4bc1e05b091d69a83af15ee6bc8140cf673", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/157032aa380a70b68d09ed71e2c9450b0fb43a7e4afdfb59723a4a1014455b5fbf0a2558d5895cc52cdeb982cccb43b00a9661ba3c810ab5d330c2505aece729", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2e21b58962c17057cbf664a85efa968886862a20ff7b9d2be95f22136bda3f84b60d1c259f2238358ddb30c3522eb4365b3fef46c58e395fcc6c5a342d33e2f"} 14:25:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:25:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:25:14 INFO - Contents: 14:25:14 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55b24d8fb29fcfd9109555e9c78a21a5aae83f6de1d2827d713473654e074d28768a4af677cae326daffcc7983aa6575e7d3c334c773bf9a2f07280b32aab6b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/087f06ac72f47b9eac85b2d6157e42f105650e40223f3c5c6a34f5b9d83112330d39b74eb96ab712d553ca663997a4bc1e05b091d69a83af15ee6bc8140cf673", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/157032aa380a70b68d09ed71e2c9450b0fb43a7e4afdfb59723a4a1014455b5fbf0a2558d5895cc52cdeb982cccb43b00a9661ba3c810ab5d330c2505aece729", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2e21b58962c17057cbf664a85efa968886862a20ff7b9d2be95f22136bda3f84b60d1c259f2238358ddb30c3522eb4365b3fef46c58e395fcc6c5a342d33e2f"} 14:25:14 INFO - Running post-run listener: copy_logs_to_upload_dir 14:25:14 INFO - Copying logs to upload dir... 14:25:14 INFO - mkdir: /builds/slave/test/build/upload/logs 14:25:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.315051 ========= master_lag: 0.37 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-03-28 14:25:14.914436) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 14:25:14.919890) ========= 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=a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746 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/55b24d8fb29fcfd9109555e9c78a21a5aae83f6de1d2827d713473654e074d28768a4af677cae326daffcc7983aa6575e7d3c334c773bf9a2f07280b32aab6b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/087f06ac72f47b9eac85b2d6157e42f105650e40223f3c5c6a34f5b9d83112330d39b74eb96ab712d553ca663997a4bc1e05b091d69a83af15ee6bc8140cf673", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/157032aa380a70b68d09ed71e2c9450b0fb43a7e4afdfb59723a4a1014455b5fbf0a2558d5895cc52cdeb982cccb43b00a9661ba3c810ab5d330c2505aece729", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2e21b58962c17057cbf664a85efa968886862a20ff7b9d2be95f22136bda3f84b60d1c259f2238358ddb30c3522eb4365b3fef46c58e395fcc6c5a342d33e2f"} build_url:https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011209 build_url: 'https://queue.taskcluster.net/v1/task/E4-tXzFeS-KdUQc8v92AhQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/55b24d8fb29fcfd9109555e9c78a21a5aae83f6de1d2827d713473654e074d28768a4af677cae326daffcc7983aa6575e7d3c334c773bf9a2f07280b32aab6b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/087f06ac72f47b9eac85b2d6157e42f105650e40223f3c5c6a34f5b9d83112330d39b74eb96ab712d553ca663997a4bc1e05b091d69a83af15ee6bc8140cf673", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/157032aa380a70b68d09ed71e2c9450b0fb43a7e4afdfb59723a4a1014455b5fbf0a2558d5895cc52cdeb982cccb43b00a9661ba3c810ab5d330c2505aece729", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2e21b58962c17057cbf664a85efa968886862a20ff7b9d2be95f22136bda3f84b60d1c259f2238358ddb30c3522eb4365b3fef46c58e395fcc6c5a342d33e2f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 14:25:14.955762) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:25:14.956083) ========= 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=a0d2d2de893abaa8702a9954000001a7-1490735049.462592-1879237746 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004688 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:25:15.004939) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 14:25:15.008301) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 14:25:15.008685) ========= ========= Total master_lag: 0.53 =========